Apr 03 15:21:03 tubestereo volumiossh-tunnel[20871]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Apr 03 15:21:03 tubestereo autossh[17507]: ssh exited with error status 255; restarting ssh Apr 03 15:21:03 tubestereo autossh[17507]: starting ssh (count 4) Apr 03 15:21:03 tubestereo autossh[17507]: ssh child pid is 20902 Apr 03 15:21:04 tubestereo go-librespot[17258]: time="2026-04-03T15:21:04-06:00" level=error msg="did not receive last pong from dealer, 150s passed" Apr 03 15:21:22 tubestereo go-librespot[17258]: time="2026-04-03T15:21:22-06: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" Apr 03 15:21:28 tubestereo volumiossh-tunnel[20902]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Apr 03 15:21:28 tubestereo autossh[17507]: ssh exited with error status 255; restarting ssh Apr 03 15:21:28 tubestereo autossh[17507]: starting ssh (count 5) Apr 03 15:21:28 tubestereo autossh[17507]: ssh child pid is 20947 Apr 03 15:21:34 tubestereo go-librespot[17258]: time="2026-04-03T15:21:34-06:00" level=error msg="did not receive last pong from dealer, 180s passed" Apr 03 15:21:44 tubestereo volumio[16896]: info: Tunnel connection is inactive, restarting it Apr 03 15:21:44 tubestereo volumio[16896]: info: Starting Tunnel 1 Apr 03 15:21:44 tubestereo volumio[16896]: info: Starting Tunnel Connection Checker Apr 03 15:21:44 tubestereo sudo[20969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 03 15:21:44 tubestereo sudo[20969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 15:21:44 tubestereo autossh[17507]: received signal to exit (15) Apr 03 15:21:44 tubestereo systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Apr 03 15:21:44 tubestereo systemd[1]: sshtunnel.service: Deactivated successfully. Apr 03 15:21:44 tubestereo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Apr 03 15:21:44 tubestereo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 03 15:21:44 tubestereo sudo[20969]: pam_unix(sudo:session): session closed for user root Apr 03 15:21:44 tubestereo volumio[16896]: info: Remote SSH Started Apr 03 15:21:44 tubestereo autossh[20972]: port set to 0, monitoring disabled Apr 03 15:21:44 tubestereo autossh[20972]: starting ssh (count 1) Apr 03 15:21:44 tubestereo autossh[20972]: ssh child pid is 20975 Apr 03 15:21:47 tubestereo go-librespot[17258]: time="2026-04-03T15:21:47-06:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: device or resource busy" Apr 03 15:21:56 tubestereo volumio[16896]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 03 15:22:04 tubestereo go-librespot[17258]: time="2026-04-03T15:22:04-06:00" level=error msg="did not receive last pong from dealer, 210s passed" Apr 03 15:22:08 tubestereo volumiossh-tunnel[20975]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Apr 03 15:22:08 tubestereo autossh[20972]: ssh exited prematurely with status 255; autossh exiting Apr 03 15:22:08 tubestereo systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Apr 03 15:22:08 tubestereo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 03 15:22:08 tubestereo volumio[16896]: info: CoreCommandRouter::volumioGetState Apr 03 15:22:08 tubestereo volumio[16896]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.3aTUacxrxrVaaGCSIpY7kXV2NI82.925a9e7752725a0051c765aa892b3ae5.state.status' Apr 03 15:22:09 tubestereo systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Apr 03 15:22:09 tubestereo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Apr 03 15:22:09 tubestereo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 03 15:22:09 tubestereo autossh[21020]: port set to 0, monitoring disabled Apr 03 15:22:09 tubestereo autossh[21020]: starting ssh (count 1) Apr 03 15:22:09 tubestereo autossh[21020]: ssh child pid is 21023 Apr 03 15:22:12 tubestereo go-librespot[17258]: time="2026-04-03T15:22:12-06:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Apr 03 15:22:33 tubestereo volumiossh-tunnel[21023]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Apr 03 15:22:33 tubestereo autossh[21020]: ssh exited prematurely with status 255; autossh exiting Apr 03 15:22:33 tubestereo systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Apr 03 15:22:33 tubestereo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 03 15:22:34 tubestereo go-librespot[17258]: time="2026-04-03T15:22:34-06:00" level=error msg="did not receive last pong ack from accesspoint, 329s passed" Apr 03 15:22:34 tubestereo go-librespot[17258]: time="2026-04-03T15:22:34-06:00" level=error msg="did not receive last pong from dealer, 240s passed" Apr 03 15:22:34 tubestereo go-librespot[17258]: panic: runtime error: invalid memory address or nil pointer dereference Apr 03 15:22:34 tubestereo go-librespot[17258]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f6f14] Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 11 gp=0x199c368 m=8 mp=0x1d85008 [running]: Apr 03 15:22:34 tubestereo go-librespot[17258]: panic({0x82e098, 0xf78250}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x185af20 sp=0x185aecc pc=0xa92ec Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.panicmem(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/panic.go:262 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.sigpanic() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x185af50 sp=0x185af20 pc=0xabca4 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x1cf80b8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:380 +0x284 fp=0x185afe4 sp=0x185af54 pc=0x4f6f14 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:277 +0x28 fp=0x185afec sp=0x185afe4 pc=0x4f63e8 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x185afec sp=0x185afec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 56 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:277 +0x15c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 1 gp=0x1802128 m=nil [select, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a42ce8 sp=0x1a42cd4 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x1a42df8, 0x19a9dc4, 0x0, 0x0, 0x2, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1a42d98 sp=0x1a42ce8 pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x1bba180, 0x1a42e90) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/zeroconf/zeroconf.go:283 +0x21c fp=0x1a42e3c sp=0x1a42d98 pc=0x61db0c Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*App).withAppPlayer(0x18ce800, {0xa260ac, 0xfdcff0}, 0x1a08050) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:340 +0x580 fp=0x1a42ed0 sp=0x1a42e3c pc=0x6ab088 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*App).withCredentials(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:209 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*App).SpotifyToken(0x18ce800, {0xa260ac, 0xfdcff0}, {0x189afc8, 0x8}, {0x18a03c0, 0x122}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x1a42f00 sp=0x1a42ed0 pc=0x6aa4b0 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.main() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:593 +0x660 fp=0x1a42fa8 sp=0x1a42f00 pc=0x6ad58c Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.main() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x1a42fec sp=0x1a42fa8 pc=0x6f390 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a42fec sp=0x1a42fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 2 gp=0x18027e8 m=nil [force gc (idle), 3 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960edc, 0xfd0f08, 0x11, 0xa, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x184afd4 sp=0x184afc0 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goparkunlock(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:466 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.forcegchelper() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x184afec sp=0x184afd4 pc=0x6f7f4 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x184afec sp=0x184afec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.init.6 in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:361 +0x1c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 3 gp=0x1802b48 m=nil [GC sweep wait]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960edc, 0xfd17b0, 0xc, 0x9, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x184b7c4 sp=0x184b7b0 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goparkunlock(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:466 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.bgsweep(0x1830050) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x184b7e4 sp=0x184b7c4 pc=0x5768c Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcenable.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x184b7ec sp=0x184b7e4 pc=0x46c3c Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x184b7ec sp=0x184b7ec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.gcenable in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 4 gp=0x1802c68 m=nil [GC scavenge wait]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960edc, 0xfd20d0, 0xd, 0xa, 0x2) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x184bfb4 sp=0x184bfa0 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goparkunlock(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:466 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.(*scavengerState).park(0xfd20d0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x184bfc8 sp=0x184bfb4 pc=0x54a28 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.bgscavenge(0x1830050) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x184bfe4 sp=0x184bfc8 pc=0x5516c Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcenable.gowrap2() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x184bfec sp=0x184bfe4 pc=0x46be8 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x184bfec sp=0x184bfec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.gcenable in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 18 gp=0x1882248 m=nil [GOMAXPROCS updater (idle), 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960edc, 0xfd1548, 0x12, 0xa, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x18467a4 sp=0x1846790 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goparkunlock(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:466 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.updateMaxProcsGoroutine() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x18467ec sp=0x18467a4 pc=0x7f174 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18467ec sp=0x18467ec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 19 gp=0x18825a8 m=nil [finalizer wait, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960d84, 0xfdd0e0, 0x10, 0xa, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1846f88 sp=0x1846f74 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.runFinalizers() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x1846fec sp=0x1846f88 pc=0x45888 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1846fec sp=0x1846fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.createfing in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 20 gp=0x1969b08 m=nil [cleanup wait, 21 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960edc, 0xfd22a8, 0x2e, 0xa, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x184a7a4 sp=0x184a790 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goparkunlock(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:466 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.(*cleanupQueue).dequeue(0xfd2248) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x184a7c4 sp=0x184a7a4 pc=0x41cb0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.runCleanups() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x184a7ec sp=0x184a7c4 pc=0x4284c Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x184a7ec sp=0x184a7ec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 34 gp=0x199cb48 m=nil [IO wait, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960ecc, 0xb6166010, 0x2, 0x2, 0x5) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x185dcf0 sp=0x185dcdc pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.netpollblock(0xb6166000, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x185dd08 sp=0x185dcf0 pc=0x675a0 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.runtime_pollWait(0xb6166000, 0x72) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x185dd1c sp=0x185dd08 pc=0xa8864 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).wait(0x1a0c018, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x185dd30 sp=0x185dd1c pc=0x134df4 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).waitRead(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*FD).Accept(0x1a0c000) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x185dd78 sp=0x185dd30 pc=0x1395b0 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*netFD).accept(0x1a0c000) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x185dde0 sp=0x185dd78 pc=0x1aa9d8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*TCPListener).accept(0x1a24000) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x185de34 sp=0x185dde0 pc=0x1c2304 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*TCPListener).Accept(0x1a24000) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x185de50 sp=0x185de34 pc=0x1c134c Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*onceCloseListener).Accept(0x18700a0) Apr 03 15:22:34 tubestereo go-librespot[17258]: :1 +0x34 fp=0x185de68 sp=0x185de50 pc=0x367710 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Server).Serve(0x1a8c008, {0xa258a8, 0x1a24000}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x185df00 sp=0x185de68 pc=0x3408c8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.Serve(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:2971 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*ConcreteApiServer).serve(0x189e960) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x185dfe4 sp=0x185df00 pc=0x69fdac Apr 03 15:22:34 tubestereo go-librespot[17258]: main.NewApiServer.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x185dfec sp=0x185dfe4 pc=0x69eae4 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x185dfec sp=0x185dfec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by main.NewApiServer in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/api_server.go:332 +0x310 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 57 gp=0x199cc68 m=nil [chan receive, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960d64, 0x18901b8, 0xe, 0x7, 0x2) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x184cf3c sp=0x184cf28 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.chanrecv(0x1890180, 0x184cfd0, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x184cf84 sp=0x184cf3c pc=0x3414c Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.chanrecv2(0x1890180, 0x184cfd0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x184cf98 sp=0x184cf84 pc=0x33bf0 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*App).withAppPlayer.func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:284 +0x68 fp=0x184cfec sp=0x184cf98 pc=0x6abd40 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x184cfec sp=0x184cfec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by main.(*App).withAppPlayer in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:281 +0x49c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 58 gp=0x1a3c128 m=nil [chan receive, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960d64, 0x1890178, 0xe, 0x7, 0x2) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x184d740 sp=0x184d72c pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.chanrecv(0x1890140, 0x184d7e0, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x184d788 sp=0x184d740 pc=0x3414c Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.chanrecv2(0x1890140, 0x184d7e0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x184d79c sp=0x184d788 pc=0x33bf0 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*App).withAppPlayer.func2() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:300 +0x6c fp=0x184d7ec sp=0x184d79c pc=0x6ab8a4 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x184d7ec sp=0x184d7ec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by main.(*App).withAppPlayer in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:297 +0x53c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 40 gp=0x1a3c368 m=nil [GC worker (idle)]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960d90, 0x1a78400, 0x1c, 0xa, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19b9f88 sp=0x19b9f74 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcBgMarkWorker(0x1c5cc80) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x19b9fe4 sp=0x19b9f88 pc=0x49f34 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x19b9fec sp=0x19b9fe4 pc=0x49e14 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19b9fec sp=0x19b9fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.gcBgMarkStartWorkers in goroutine 23 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 41 gp=0x1a3c488 m=nil [GC worker (idle), 9 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960d90, 0x1a78600, 0x1c, 0xa, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a31788 sp=0x1a31774 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcBgMarkWorker(0x1c5cc80) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1a317e4 sp=0x1a31788 pc=0x49f34 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1a317ec sp=0x1a317e4 pc=0x49e14 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a317ec sp=0x1a317ec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.gcBgMarkStartWorkers in goroutine 23 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 42 gp=0x1a3c5a8 m=nil [GC worker (idle), 9 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960d90, 0x1a78800, 0x1c, 0xa, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a2cf88 sp=0x1a2cf74 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcBgMarkWorker(0x1c5cc80) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1a2cfe4 sp=0x1a2cf88 pc=0x49f34 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1a2cfec sp=0x1a2cfe4 pc=0x49e14 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a2cfec sp=0x1a2cfec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.gcBgMarkStartWorkers in goroutine 23 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 43 gp=0x1a3c6c8 m=nil [GC worker (idle), 3 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960d90, 0x1a78a00, 0x1c, 0xa, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1847f88 sp=0x1847f74 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcBgMarkWorker(0x1c5cc80) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1847fe4 sp=0x1847f88 pc=0x49f34 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1847fec sp=0x1847fe4 pc=0x49e14 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1847fec sp=0x1847fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by runtime.gcBgMarkStartWorkers in goroutine 23 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 5 gp=0x199c008 m=nil [IO wait, 3 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960ecc, 0xb6165a10, 0x2, 0x2, 0x5) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1858e88 sp=0x1858e74 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.netpollblock(0xb6165a00, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1858ea0 sp=0x1858e88 pc=0x675a0 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.runtime_pollWait(0xb6165a00, 0x72) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1858eb4 sp=0x1858ea0 pc=0xa8864 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).wait(0x1a200b8, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1858ec8 sp=0x1858eb4 pc=0x134df4 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).waitRead(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*FD).RawRead(0x1a200a0, 0x1cea900) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x154 fp=0x1858ef4 sp=0x1858ec8 pc=0x13a168 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*rawConn).Read(0x189c5d8, 0x1cea900) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1858f14 sp=0x1858ef4 pc=0x1bc9b4 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1a081c0, 0x1cea8d0, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x140 fp=0x1858f44 sp=0x1858f14 pc=0x5b7e28 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x1a020c8, {0x1bc0000, 0x10000, 0x10000}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv4/payload_cmsg.go:31 +0x1a0 fp=0x1858f9c sp=0x1858f44 pc=0x5bbb1c Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/grandcat/zeroconf.(*Server).recv4(0x19d2580, 0x1a020c0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xdc fp=0x1858fe0 sp=0x1858f9c pc=0x618bf8 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1858fec sp=0x1858fe0 pc=0x6188c8 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1858fec sp=0x1858fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 25 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 6 gp=0x199c128 m=nil [IO wait, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960ecc, 0xb6165810, 0x2, 0x2, 0x5) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19cde60 sp=0x19cde4c pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.netpollblock(0xb6165800, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x19cde78 sp=0x19cde60 pc=0x675a0 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.runtime_pollWait(0xb6165800, 0x72) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x19cde8c sp=0x19cde78 pc=0xa8864 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).wait(0x1a20108, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x19cdea0 sp=0x19cde8c pc=0x134df4 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).waitRead(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*FD).RawRead(0x1a200f0, 0x1801ce0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x154 fp=0x19cdecc sp=0x19cdea0 pc=0x13a168 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*rawConn).Read(0x189c600, 0x1801ce0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x19cdeec sp=0x19cdecc pc=0x1bc9b4 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1a081d0, 0x1801cb0, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x140 fp=0x19cdf1c sp=0x19cdeec pc=0x5b7e28 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1a020f8, {0x1b86000, 0x10000, 0x10000}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv6/payload_cmsg.go:31 +0x188 fp=0x19cdf9c sp=0x19cdf1c pc=0x5bf588 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/grandcat/zeroconf.(*Server).recv6(0x19d2580, 0x1a020f0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xdc fp=0x19cdfe0 sp=0x19cdf9c pc=0x618ddc Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x19cdfec sp=0x19cdfe0 pc=0x61886c Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19cdfec sp=0x19cdfec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 25 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 56 gp=0x18038c8 m=nil [select, 12 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19a7930 sp=0x19a791c pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x19a7f70, 0x19a7b20, 0x0, 0x0, 0xa, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x19a79e0 sp=0x19a7930 pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*AppPlayer).Run(0x1a20140, {0xa260ac, 0xfdcff0}, 0x1a0ae40, 0x1a0ae80) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/player.go:661 +0x368 fp=0x19a7fd4 sp=0x19a79e0 pc=0x6b3970 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*App).withAppPlayer.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:274 +0x48 fp=0x19a7fec sp=0x19a7fd4 pc=0x6abe18 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19a7fec sp=0x19a7fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by main.(*App).withAppPlayer in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/main.go:274 +0x410 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 55 gp=0x1803e68 m=nil [select, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19cd674 sp=0x19cd660 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x19cd780, 0x19cd74c, 0x0, 0x0, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x19cd724 sp=0x19cd674 pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1b742d0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/player/player.go:215 +0x1f4 fp=0x19cd7e4 sp=0x19cd724 pc=0x5818e0 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/player/player.go:197 +0x28 fp=0x19cd7ec sp=0x19cd7e4 pc=0x5814c8 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19cd7ec sp=0x19cd7ec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/player/player.go:197 +0x220 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 59 gp=0x1a3d0e8 m=nil [IO wait, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960ecc, 0xb6165c10, 0x2, 0x2, 0x5) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x184ddac sp=0x184dd98 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.netpollblock(0xb6165c00, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x184ddc4 sp=0x184ddac pc=0x675a0 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.runtime_pollWait(0xb6165c00, 0x72) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x184ddd8 sp=0x184ddc4 pc=0xa8864 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).wait(0x1a20068, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x184ddec sp=0x184ddd8 pc=0x134df4 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).waitRead(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*FD).Accept(0x1a20050) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x184de34 sp=0x184ddec pc=0x1395b0 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*netFD).accept(0x1a20050) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x184de9c sp=0x184de34 pc=0x1aa9d8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*TCPListener).accept(0x1a02060) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x184def0 sp=0x184de9c pc=0x1c2304 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*TCPListener).Accept(0x1a02060) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x184df0c sp=0x184def0 pc=0x1c134c Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*onceCloseListener).Accept(0x1a01440) Apr 03 15:22:34 tubestereo go-librespot[17258]: :1 +0x34 fp=0x184df24 sp=0x184df0c pc=0x367710 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Server).Serve(0x1cf8218, {0xa258a8, 0x1a02060}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x184dfbc sp=0x184df24 pc=0x3408c8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.Serve(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:2971 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x184dfec sp=0x184dfbc pc=0x61dc08 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x184dfec sp=0x184dfec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/zeroconf/zeroconf.go:280 +0x154 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 143 gp=0x199c248 m=nil [select]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a43b60 sp=0x1a43b4c pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x1a43c68, 0x1a43c38, 0x0, 0x0, 0x2, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1a43c10 sp=0x1a43b60 pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.doBlockingWithCtx[...]({0xa260e4, 0x1800a50}, {0x182c048, 0x13}, 0x18283a8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:71 +0x280 fp=0x1a43c90 sp=0x1a43c10 pc=0x1cc630 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.cgoLookupIP({0xa260e4, 0x1800a50}, {0x903a4e, 0x3}, {0x182c048, 0x13}) Apr 03 15:22:34 tubestereo volumio[16896]: info: Connection to go-librespot Websocket closed Apr 03 15:22:34 tubestereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:228 +0x98 fp=0x1a43cc0 sp=0x1a43c90 pc=0x19c334 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).lookupIP(0xfd1660, {0xa260e4, 0x1800a50}, {0x903a4e, 0x3}, {0x182c048, 0x13}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/lookup_unix.go:64 +0x90 fp=0x1a43efc sp=0x1a43cc0 pc=0x1b7a0c Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).lookupIP-fm({0xa260e4, 0x1800a50}, {0x903a4e, 0x3}, {0x182c048, 0x13}) Apr 03 15:22:34 tubestereo go-librespot[17258]: :1 +0x58 fp=0x1a43f30 sp=0x1a43efc pc=0x1d2324 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.init.func1({0xa260e4, 0x1800a50}, 0x18161e8, {0x903a4e, 0x3}, {0x182c048, 0x13}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/hook.go:21 +0x4c fp=0x1a43f60 sp=0x1a43f30 pc=0x1999a8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).lookupIPAddr.func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/lookup.go:335 +0x58 fp=0x1a43f9c sp=0x1a43f60 pc=0x1b724c Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/singleflight.(*Group).doCall(0xfd1668, 0x1800a80, {0x18180c0, 0x17}, 0x1d8a580) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x1a43fd4 sp=0x1a43f9c pc=0x197c48 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/singleflight.(*Group).DoChan.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x1a43fec sp=0x1a43fd4 pc=0x197c00 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a43fec sp=0x1a43fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by internal/singleflight.(*Group).DoChan in goroutine 80 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x304 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 12 gp=0x199c488 m=nil [select]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x18596c4 sp=0x18596b0 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x1859824, 0x185979c, 0x0, 0x0, 0x2, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1859774 sp=0x18596c4 pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Transport).getConn(0xf812a8, 0x1800b40, {{}, 0x0, {0x1ac8b40, 0x5}, {0x1b70180, 0x1b}, 0x1}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/transport.go:1523 +0x370 fp=0x1859870 sp=0x1859774 pc=0x351440 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Transport).roundTrip(0xf812a8, 0x1b56428) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/transport.go:685 +0x978 fp=0x185995c sp=0x1859870 pc=0x34d528 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Transport).RoundTrip(0xf812a8, 0x1b56428) Apr 03 15:22:34 tubestereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/roundtrip.go:33 +0x2c fp=0x1859974 sp=0x185995c pc=0x35ba84 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.send(0x1b56428, {0xa21b28, 0xf812a8}, {0x0, 0x0, 0x0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/client.go:259 +0x53c fp=0x1859aa0 sp=0x1859974 pc=0x2ed3ec Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Client).send(0x1d8a600, 0x1b56428, {0x0, 0x0, 0x0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x1859ae4 sp=0x1859aa0 pc=0x2eccd8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Client).do(0x1d8a600, 0x1b56428) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/client.go:729 +0x9a8 fp=0x1859bf4 sp=0x1859ae4 pc=0x2ef340 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Client).Do(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/client.go:587 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.handshakeRequest({0xa2611c, 0x1a0d360}, {0x1ac8960, 0x1cb}, 0x1800ab0, 0x0, {0x1818120, 0x18}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/dial.go:210 +0x9e4 fp=0x1859ccc sp=0x1859bf4 pc=0x496f44 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.dial({0xa26090, 0xfdcff0}, {0x1ac8960, 0x1cb}, 0x1859de0, {0x0, 0x0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/dial.go:132 +0x24c fp=0x1859d8c sp=0x1859ccc pc=0x495bd8 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.Dial(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/dial.go:110 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/dealer.(*Dealer).connect(0x1cf8168, {0xa260ac, 0xfdcff0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/dealer/dealer.go:87 +0x31c fp=0x1859e1c sp=0x1859d8c pc=0x5a09e4 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect(0x1cf8168) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/dealer/dealer.go:288 +0x2c fp=0x1859e54 sp=0x1859e1c pc=0x5a1c74 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect-fm() Apr 03 15:22:34 tubestereo go-librespot[17258]: :1 +0x28 fp=0x1859e64 sp=0x1859e54 pc=0x5a35d8 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x1859e70 sp=0x1859e64 pc=0x4ea1ec Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x1859f14, {0xa24f50, 0x189ef60}, 0x0, {0x0, 0x0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x1859eec sp=0x1859e70 pc=0x4ea668 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x1859f68, {0xa24f50, 0x189ef60}, 0x0, {0x0, 0x0}) Apr 03 15:22:34 tubestereo systemd[1]: go-librespot-daemon.service: Consumed 2.367s CPU time. Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x1859f1c sp=0x1859eec pc=0x4ea1a4 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.RetryNotify(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.Retry(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x1cf8168) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/dealer/dealer.go:237 +0x688 fp=0x1859fe4 sp=0x1859f1c pc=0x5a17c8 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/dealer/dealer.go:125 +0x28 fp=0x1859fec sp=0x1859fe4 pc=0x5a2c94 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1859fec sp=0x1859fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 56 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/dealer/dealer.go:125 +0x98 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 13 gp=0x199c6c8 m=nil [runnable]: Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.(*Conn).Close(0x1c0e008, 0x3f4, {0x0, 0x0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/close.go:109 +0x1c8 fp=0x1a45f34 sp=0x1a45f34 pc=0x492420 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x1cf8168) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/dealer/dealer.go:150 +0x2a0 fp=0x1a45fe4 sp=0x1a45f34 pc=0x5a0f10 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/dealer/dealer.go:129 +0x28 fp=0x1a45fec sp=0x1a45fe4 pc=0x5a2c40 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a45fec sp=0x1a45fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 56 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/dealer/dealer.go:129 +0x15c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 102 gp=0x1c34488 m=nil [IO wait, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960ecc, 0xb57f8e10, 0x2, 0x2, 0x5) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19a58d4 sp=0x19a58c0 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.netpollblock(0xb57f8e00, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x19a58ec sp=0x19a58d4 pc=0x675a0 Apr 03 15:22:34 tubestereo systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.runtime_pollWait(0xb57f8e00, 0x72) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x19a5900 sp=0x19a58ec pc=0xa8864 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).wait(0x1a0c608, 0x72, 0x0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x19a5914 sp=0x19a5900 pc=0x134df4 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*pollDesc).waitRead(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/poll.(*FD).Read(0x1a0c5f0, {0x1c43000, 0x1000, 0x1000}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x19a595c sp=0x19a5914 pc=0x135f74 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*netFD).Read(0x1a0c5f0, {0x1c43000, 0x1000, 0x1000}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x19a5988 sp=0x19a595c pc=0x1a8c28 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*conn).Read(0x1a04e98, {0x1c43000, 0x1000, 0x1000}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x19a59b4 sp=0x19a5988 pc=0x1b842c Apr 03 15:22:34 tubestereo go-librespot[17258]: io.(*multiReader).Read(0x1b7bce0, {0x1c43000, 0x1000, 0x1000}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x19a59e0 sp=0x19a59b4 pc=0xef364 Apr 03 15:22:34 tubestereo go-librespot[17258]: bufio.(*Reader).fill(0x1ba3b00) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x19a5a04 sp=0x19a59e0 pc=0x2b6390 Apr 03 15:22:34 tubestereo go-librespot[17258]: bufio.(*Reader).ReadByte(0x1ba3b00) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x19a5a10 sp=0x19a5a04 pc=0x2b6c0c Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.readFrameHeader(0x1ba3b00, {0x1cd62bc, 0x8, 0x8}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x19a5a50 sp=0x19a5a10 pc=0x498164 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1cd6288, {0xa26090, 0xfdcff0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x19a5b10 sp=0x19a5a50 pc=0x49b01c Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.(*Conn).readLoop(0x1cd6288, {0xa26090, 0xfdcff0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x19a5bd4 sp=0x19a5b10 pc=0x49ab08 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.(*Conn).reader(0x1cd6288, {0xa26090, 0xfdcff0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x19a5c58 sp=0x19a5bd4 pc=0x49bc20 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.(*Conn).Reader(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.(*Conn).Read(0x1cd6288, {0xa26090, 0xfdcff0}) Apr 03 15:22:34 tubestereo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x19a5c80 sp=0x19a5c58 pc=0x49a0a0 Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*ConcreteApiServer).serve.func17({0xa25968, 0x1cf8848}, 0x1cf86e8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/cmd/daemon/api_server.go:636 +0x3b0 fp=0x19a5cfc sp=0x19a5c80 pc=0x6a062c Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.HandlerFunc.ServeHTTP(0x18341a8, {0xa25968, 0x1cf8848}, 0x1cf86e8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x19a5d0c sp=0x19a5cfc pc=0x33cf4c Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*ServeMux).ServeHTTP(0x187c000, {0xa25968, 0x1cf8848}, 0x1cf86e8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x19a5d4c sp=0x19a5d0c pc=0x33ee3c Apr 03 15:22:34 tubestereo go-librespot[17258]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa25968, 0x1cf8848}, 0x1cf86e8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x19a5d74 sp=0x19a5d4c pc=0x69ffa8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.HandlerFunc.ServeHTTP(0x1810170, {0xa25968, 0x1cf8848}, 0x1cf86e8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x19a5d84 sp=0x19a5d74 pc=0x33cf4c Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.serverHandler.ServeHTTP({0x1a8c008}, {0xa25968, 0x1cf8848}, 0x1cf86e8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x19a5da0 sp=0x19a5d84 pc=0x35c2b8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*conn).serve(0x1bba600, {0xa260c8, 0x18281f8}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x19a5fdc sp=0x19a5da0 pc=0x33b008 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Server).Serve.gowrap3() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x19a5fec sp=0x19a5fdc pc=0x340d40 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19a5fec sp=0x19a5fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by net/http.(*Server).Serve in goroutine 34 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/server.go:3493 +0x42c Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 104 gp=0x1c345a8 m=nil [select, 24 minutes]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a31e94 sp=0x1a31e80 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x1a31fa8, 0x1a31f6c, 0x0, 0x0, 0x5, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1a31f44 sp=0x1a31e94 pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1cd6288) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x1a31fe4 sp=0x1a31f44 pc=0x494d20 Apr 03 15:22:34 tubestereo go-librespot[17258]: nhooyr.io/websocket.newConn.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x1a31fec sp=0x1a31fe4 pc=0x4949b0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a31fec sp=0x1a31fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by nhooyr.io/websocket.newConn in goroutine 102 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 80 gp=0x1b065a8 m=nil [select]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x185f868 sp=0x185f854 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x185f9d8, 0x185f944, 0x0, 0x0, 0x2, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x185f918 sp=0x185f868 pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).lookupIPAddr(0xfd1660, {0xa2611c, 0x1a0d2c0}, {0x903a4e, 0x3}, {0x182c048, 0x13}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/lookup.go:343 +0x394 fp=0x185fa20 sp=0x185f918 pc=0x1b68b0 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).internetAddrList(0xfd1660, {0xa2611c, 0x1a0d2c0}, {0x903a4e, 0x3}, {0x182c048, 0x18}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/ipsock.go:289 +0x6b0 fp=0x185fab4 sp=0x185fa20 pc=0x1b486c Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).resolveAddrList(0xfd1660, {0xa2611c, 0x1a0d2c0}, {0x903e50, 0x4}, {0x903a4e, 0x3}, {0x182c048, 0x18}, {0x0, ...}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/dial.go:353 +0x510 fp=0x185fb3c sp=0x185fab4 pc=0x19f1a8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Dialer).DialContext(0x185fc68, {0xa2611c, 0x1a0d2c0}, {0x903a4e, 0x3}, {0x182c048, 0x18}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/dial.go:560 +0x43c fp=0x185fc38 sp=0x185fb3c pc=0x1a00e4 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/proxy.direct.DialContext(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/proxy.(*direct).DialContext(0xfdcff0, {0xa2611c, 0x1a0d2c0}, {0x903a4e, 0x3}, {0x182c048, 0x18}) Apr 03 15:22:34 tubestereo go-librespot[17258]: :1 +0x74 fp=0x185fccc sp=0x185fc38 pc=0x4f1ff0 Apr 03 15:22:34 tubestereo go-librespot[17258]: golang.org/x/net/proxy.Dial({0xa2611c, 0x1a0d2c0}, {0x903a4e, 0x3}, {0x182c048, 0x18}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0xac fp=0x185fd08 sp=0x185fccc pc=0x4efeb4 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0x1cf80b8, {0xa260ac, 0xfdcff0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:95 +0x2a8 fp=0x185fd80 sp=0x185fd08 pc=0x4f4974 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0x1cf80b8, {0xa260ac, 0xfdcff0}, 0x1c288d0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:205 +0xd4 fp=0x185fde4 sp=0x185fd80 pc=0x4f5a7c Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0x1cf80b8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:394 +0xcc fp=0x185fe0c sp=0x185fde4 pc=0x4f703c Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Apr 03 15:22:34 tubestereo go-librespot[17258]: :1 +0x28 fp=0x185fe1c sp=0x185fe0c pc=0x4fb294 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x185fe28 sp=0x185fe1c pc=0x4ea1ec Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x185fecc, {0xa24f50, 0x182ae40}, 0x0, {0x0, 0x0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x185fea4 sp=0x185fe28 pc=0x4ea668 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x185ff5c, {0xa24f50, 0x182ae40}, 0x0, {0x0, 0x0}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x185fed4 sp=0x185fea4 pc=0x4ea1a4 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.RetryNotify(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/cenkalti/backoff/v4.Retry(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x1cf80b8) Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:335 +0x434 fp=0x185ffe4 sp=0x185fed4 pc=0x4f689c Apr 03 15:22:34 tubestereo go-librespot[17258]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:403 +0x28 fp=0x185ffec sp=0x185ffe4 pc=0x4f71f0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x185ffec sp=0x185ffec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 10 Apr 03 15:22:34 tubestereo go-librespot[17258]: /src/ap/ap.go:403 +0x120 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 144 gp=0x199c908 m=3 mp=0x184f008 [syscall]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.cgocall(0x74cab0, 0x19caefc) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/cgocall.go:167 +0x50 fp=0x19caee4 sp=0x19caecc pc=0xa628c Apr 03 15:22:34 tubestereo go-librespot[17258]: net._C2func_getaddrinfo(0x1818108, 0x0, 0x1d8a5a0, 0x1816200) Apr 03 15:22:34 tubestereo go-librespot[17258]: _cgo_gotypes.go:105 +0x44 fp=0x19caef8 sp=0x19caee4 pc=0x1c97c8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net._C_getaddrinfo.func1(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix_cgo.go:84 Apr 03 15:22:34 tubestereo go-librespot[17258]: net._C_getaddrinfo(0x1818108, 0x0, 0x1d8a5a0, 0x1816200) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix_cgo.go:84 +0x80 fp=0x19caf18 sp=0x19caef8 pc=0x1c9a8c Apr 03 15:22:34 tubestereo go-librespot[17258]: net.cgoLookupHostIP({0x903a4e, 0x3}, {0x182c048, 0x13}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:174 +0x1f4 fp=0x19caf8c sp=0x19caf18 pc=0x19bd90 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.cgoLookupIP.func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:229 +0x34 fp=0x19cafb4 sp=0x19caf8c pc=0x19c3b4 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.doBlockingWithCtx[...].func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:67 +0x54 fp=0x19cafec sp=0x19cafb4 pc=0x1cc878 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19cafec sp=0x19cafec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by net.doBlockingWithCtx[...] in goroutine 143 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:64 +0x204 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 145 gp=0x199ca28 m=nil [select]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a4086c sp=0x1a40858 pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x1a409dc, 0x1a40948, 0x0, 0x0, 0x2, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1a4091c sp=0x1a4086c pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).lookupIPAddr(0xfd1660, {0xa2611c, 0x1a0d4a0}, {0x903a4e, 0x3}, {0x1b70180, 0x17}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/lookup.go:343 +0x394 fp=0x1a40a24 sp=0x1a4091c pc=0x1b68b0 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).internetAddrList(0xfd1660, {0xa2611c, 0x1a0d4a0}, {0x903a4e, 0x3}, {0x1b70180, 0x1b}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/ipsock.go:289 +0x6b0 fp=0x1a40ab8 sp=0x1a40a24 pc=0x1b486c Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).resolveAddrList(0xfd1660, {0xa2611c, 0x1a0d4a0}, {0x903e50, 0x4}, {0x903a4e, 0x3}, {0x1b70180, 0x1b}, {0x0, ...}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/dial.go:353 +0x510 fp=0x1a40b40 sp=0x1a40ab8 pc=0x19f1a8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Dialer).DialContext(0x19927e0, {0xa260e4, 0x1800b70}, {0x903a4e, 0x3}, {0x1b70180, 0x1b}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/dial.go:560 +0x43c fp=0x1a40c3c sp=0x1a40b40 pc=0x1a00e4 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Dialer).DialContext-fm({0xa260e4, 0x1800b70}, {0x903a4e, 0x3}, {0x1b70180, 0x1b}) Apr 03 15:22:34 tubestereo go-librespot[17258]: :1 +0x58 fp=0x1a40c6c sp=0x1a40c3c pc=0x368914 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Transport).dial(0xf812a8, {0xa260e4, 0x1800b70}, {0x903a4e, 0x3}, {0x1b70180, 0x1b}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/transport.go:1278 +0x158 fp=0x1a40cac sp=0x1a40c6c pc=0x3506d4 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Transport).dialConn(0xf812a8, {0xa260e4, 0x1800b70}, {{}, 0x0, {0x1ac8b40, 0x5}, {0x1b70180, 0x1b}, 0x1}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/transport.go:1783 +0x6a4 fp=0x1a40f60 sp=0x1a40cac pc=0x3530b0 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Transport).dialConnFor(0xf812a8, 0x1a366c0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/transport.go:1618 +0x80 fp=0x1a40fc4 sp=0x1a40f60 pc=0x351e80 Apr 03 15:22:34 tubestereo go-librespot[17258]: net/http.(*Transport).startDialConnForLocked.func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/transport.go:1600 +0x3c fp=0x1a40fec sp=0x1a40fc4 pc=0x351d08 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a40fec sp=0x1a40fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by net/http.(*Transport).startDialConnForLocked in goroutine 12 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/http/transport.go:1599 +0x134 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 178 gp=0x199cd88 m=nil [select]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1a44b60 sp=0x1a44b4c pc=0xa97c0 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.selectgo(0x1a44c68, 0x1a44c38, 0x0, 0x0, 0x2, 0x1) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1a44c10 sp=0x1a44b60 pc=0x85000 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.doBlockingWithCtx[...]({0xa260e4, 0x1800ba0}, {0x1b70180, 0x17}, 0x1828450) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:71 +0x280 fp=0x1a44c90 sp=0x1a44c10 pc=0x1cc630 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.cgoLookupIP({0xa260e4, 0x1800ba0}, {0x903a4e, 0x3}, {0x1b70180, 0x17}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:228 +0x98 fp=0x1a44cc0 sp=0x1a44c90 pc=0x19c334 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).lookupIP(0xfd1660, {0xa260e4, 0x1800ba0}, {0x903a4e, 0x3}, {0x1b70180, 0x17}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/lookup_unix.go:64 +0x90 fp=0x1a44efc sp=0x1a44cc0 pc=0x1b7a0c Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).lookupIP-fm({0xa260e4, 0x1800ba0}, {0x903a4e, 0x3}, {0x1b70180, 0x17}) Apr 03 15:22:34 tubestereo go-librespot[17258]: :1 +0x58 fp=0x1a44f30 sp=0x1a44efc pc=0x1d2324 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.init.func1({0xa260e4, 0x1800ba0}, 0x1816298, {0x903a4e, 0x3}, {0x1b70180, 0x17}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/hook.go:21 +0x4c fp=0x1a44f60 sp=0x1a44f30 pc=0x1999a8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.(*Resolver).lookupIPAddr.func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/lookup.go:335 +0x58 fp=0x1a44f9c sp=0x1a44f60 pc=0x1b724c Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/singleflight.(*Group).doCall(0xfd1668, 0x1800bd0, {0x1b701a0, 0x1b}, 0x1d8a6e0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x1a44fd4 sp=0x1a44f9c pc=0x197c48 Apr 03 15:22:34 tubestereo go-librespot[17258]: internal/singleflight.(*Group).DoChan.gowrap1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x1a44fec sp=0x1a44fd4 pc=0x197c00 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1a44fec sp=0x1a44fec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by internal/singleflight.(*Group).DoChan in goroutine 145 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x304 Apr 03 15:22:34 tubestereo go-librespot[17258]: goroutine 179 gp=0x199d328 m=4 mp=0x1880008 [syscall]: Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.cgocall(0x74cab0, 0x19cb6fc) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/cgocall.go:167 +0x50 fp=0x19cb6e4 sp=0x19cb6cc pc=0xa628c Apr 03 15:22:34 tubestereo go-librespot[17258]: net._C2func_getaddrinfo(0x1818198, 0x0, 0x1d8a700, 0x18162b0) Apr 03 15:22:34 tubestereo go-librespot[17258]: _cgo_gotypes.go:105 +0x44 fp=0x19cb6f8 sp=0x19cb6e4 pc=0x1c97c8 Apr 03 15:22:34 tubestereo go-librespot[17258]: net._C_getaddrinfo.func1(...) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix_cgo.go:84 Apr 03 15:22:34 tubestereo go-librespot[17258]: net._C_getaddrinfo(0x1818198, 0x0, 0x1d8a700, 0x18162b0) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix_cgo.go:84 +0x80 fp=0x19cb718 sp=0x19cb6f8 pc=0x1c9a8c Apr 03 15:22:34 tubestereo go-librespot[17258]: net.cgoLookupHostIP({0x903a4e, 0x3}, {0x1b70180, 0x17}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:174 +0x1f4 fp=0x19cb78c sp=0x19cb718 pc=0x19bd90 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.cgoLookupIP.func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:229 +0x34 fp=0x19cb7b4 sp=0x19cb78c pc=0x19c3b4 Apr 03 15:22:34 tubestereo go-librespot[17258]: net.doBlockingWithCtx[...].func1() Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:67 +0x54 fp=0x19cb7ec sp=0x19cb7b4 pc=0x1cc878 Apr 03 15:22:34 tubestereo go-librespot[17258]: runtime.goexit({}) Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19cb7ec sp=0x19cb7ec pc=0xb0874 Apr 03 15:22:34 tubestereo go-librespot[17258]: created by net.doBlockingWithCtx[...] in goroutine 178 Apr 03 15:22:34 tubestereo go-librespot[17258]: /usr/local/go/src/net/cgo_unix.go:64 +0x204 Apr 03 15:22:34 tubestereo go-librespot[17257]: Aborted Apr 03 15:22:34 tubestereo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 03 15:22:34 tubestereo autossh[21055]: port set to 0, monitoring disabled Apr 03 15:22:34 tubestereo autossh[21055]: starting ssh (count 1) Apr 03 15:22:34 tubestereo autossh[21055]: ssh child pid is 21058 Apr 03 15:22:37 tubestereo volumio[16896]: info: Initializing connection to go-librespot Websocket Apr 03 15:22:37 tubestereo volumio[16896]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 03 15:22:37 tubestereo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 03 15:22:37 tubestereo systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 03 15:22:37 tubestereo systemd[1]: go-librespot-daemon.service: Consumed 2.367s CPU time. Apr 03 15:22:37 tubestereo systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 03 15:22:37 tubestereo go-librespot[21073]: go-librespot daemon starting... Apr 03 15:22:37 tubestereo go-librespot[21074]: time="2026-04-03T15:22:37-06:00" level=info msg="running go-librespot 0.6.2" Apr 03 15:22:37 tubestereo go-librespot[21074]: time="2026-04-03T15:22:37-06:00" level=debug msg="app state loaded" Apr 03 15:22:37 tubestereo go-librespot[21074]: time="2026-04-03T15:22:37-06:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 03 15:22:40 tubestereo volumio[16896]: info: Initializing connection to go-librespot Websocket Apr 03 15:22:40 tubestereo volumio[16896]: info: Connection to go-librespot Websocket established Apr 03 15:22:40 tubestereo go-librespot[21074]: time="2026-04-03T15:22:40-06:00" level=debug msg="new websocket client" Apr 03 15:22:43 tubestereo volumio[16896]: info: Getting Spotify volume Apr 03 15:22:43 tubestereo volumio[16896]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 03 15:22:43 tubestereo volumio[16896]: info: CoreCommandRouter::volumioGetState Apr 03 15:22:50 tubestereo volumiossh-tunnel[21058]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Apr 03 15:22:50 tubestereo autossh[21055]: ssh exited prematurely with status 255; autossh exiting Apr 03 15:22:50 tubestereo systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Apr 03 15:22:50 tubestereo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Apr 03 15:22:50 tubestereo go-librespot[21074]: time="2026-04-03T15:22:50-06: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" Apr 03 15:22:50 tubestereo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 03 15:22:50 tubestereo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 03 15:22:50 tubestereo volumio[16896]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 15:22:50 tubestereo volumio[16896]: Error: socket hang up Apr 03 15:22:50 tubestereo volumio[16896]: at connResetException (node:internal/errors:720:14) Apr 03 15:22:50 tubestereo volumio[16896]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 03 15:22:50 tubestereo volumio[16896]: at Socket.emit (node:events:526:35) Apr 03 15:22:50 tubestereo volumio[16896]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 03 15:22:50 tubestereo volumio[16896]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 03 15:22:50 tubestereo volumio[16896]: code: 'ECONNRESET', Apr 03 15:22:50 tubestereo volumio[16896]: response: undefined Apr 03 15:22:50 tubestereo volumio[16896]: } Apr 03 15:22:50 tubestereo volumio[16896]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 15:22:50 tubestereo systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Apr 03 15:22:50 tubestereo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Apr 03 15:22:50 tubestereo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 03 15:22:50 tubestereo autossh[21111]: port set to 0, monitoring disabled Apr 03 15:22:50 tubestereo autossh[21111]: starting ssh (count 1) Apr 03 15:22:50 tubestereo autossh[21111]: ssh child pid is 21114 Apr 03 15:22:51 tubestereo sudo[21117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-03 15:21' Apr 03 15:22:51 tubestereo sudo[21117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 15:22:51 tubestereo volumiossh-tunnel[21114]: Warning: Permanently added '[us2.myvolumio.org]:2222' (RSA) to the list of known hosts. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"