Jun 01 11:38:00 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:00 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:00 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:38:00 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:38:00 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:00 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:00 volumio volumio[1097]: info: Listing playlists Jun 01 11:38:00 volumio volumio[1097]: info: Listing playlists Jun 01 11:38:05 volumio go-librespot[1599]: time="2026-06-01T11:38:05+02:00" level=debug msg="renewing login5 access token" Jun 01 11:38:10 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:10 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:10 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:38:10 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:38:10 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:10 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:20 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:20 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:20 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:38:20 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:38:20 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:20 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:20 volumio volumio[1097]: info: Listing playlists Jun 01 11:38:20 volumio volumio[1097]: info: Listing playlists Jun 01 11:38:29 volumio go-librespot[1599]: time="2026-06-01T11:38:29+02:00" level=error msg="did not receive last pong from dealer, 150s passed" Jun 01 11:38:30 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:30 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:30 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:38:30 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:38:30 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:30 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:30 volumio bash[5958]: setdatetime-helper: all HTTPS Date fallbacks failed Jun 01 11:38:30 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jun 01 11:38:30 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jun 01 11:38:30 volumio systemd[1]: setdatetime-helper.service: Consumed 1.012s CPU time. Jun 01 11:38:37 volumio go-librespot[1599]: time="2026-06-01T11:38:37+02:00" level=debug msg="renewing login5 access token" Jun 01 11:38:38 volumio go-librespot[1599]: time="2026-06-01T11:38:38+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 172.16.0.173:48678->104.199.65.9:443: read: connection timed out" Jun 01 11:38:40 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:40 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:40 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:38:40 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:38:40 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:40 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:40 volumio volumio[1097]: info: Listing playlists Jun 01 11:38:40 volumio volumio[1097]: info: Listing playlists Jun 01 11:38:50 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:50 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:50 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:38:50 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:38:50 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:38:50 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:38:59 volumio go-librespot[1599]: time="2026-06-01T11:38:59+02:00" level=error msg="did not receive last pong from dealer, 180s passed" Jun 01 11:38:59 volumio go-librespot[1599]: time="2026-06-01T11:38:59+02:00" level=error msg="did not receive last pong ack from accesspoint, 220s passed" Jun 01 11:38:59 volumio go-librespot[1599]: panic: runtime error: invalid memory address or nil pointer dereference Jun 01 11:38:59 volumio go-librespot[1599]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f7ef0] Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20356671 gp=0x2840128 m=0 mp=0xffacd0 [running]: Jun 01 11:38:59 volumio go-librespot[1599]: panic({0x841d88, 0xfa0250}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x2910f20 sp=0x2910ecc pc=0xa92ec Jun 01 11:38:59 volumio go-librespot[1599]: runtime.panicmem(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/panic.go:262 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.sigpanic() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x2910f50 sp=0x2910f20 pc=0xabca4 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x2419b88) Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:380 +0x284 fp=0x2910fe4 sp=0x2910f54 pc=0x4f7ef0 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:277 +0x28 fp=0x2910fec sp=0x2910fe4 pc=0x4f73c4 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2910fec sp=0x2910fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 20356624 Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:277 +0x15c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 1 gp=0x2402128 m=nil [select, 3451 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2912ce0 sp=0x2912ccc pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.selectgo(0x2912df0, 0x26a3dbc, 0x0, 0x0, 0x2, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2912d90 sp=0x2912ce0 pc=0x85000 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x26ea060, 0x2912e90) Jun 01 11:38:59 volumio go-librespot[1599]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x2912e38 sp=0x2912d90 pc=0x62f43c Jun 01 11:38:59 volumio go-librespot[1599]: main.(*App).withAppPlayer(0x24f0380, {0xa3da1c, 0x1005090}, 0x24a93b0) Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x2912ed0 sp=0x2912e38 pc=0x6bcc60 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*App).withCredentials(...) Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:209 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*App).SpotifyToken(0x24f0380, {0xa3da1c, 0x1005090}, {0x24a0760, 0x19}, {0x2636000, 0x13a}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x2912f00 sp=0x2912ed0 pc=0x6bc02c Jun 01 11:38:59 volumio go-librespot[1599]: main.main() Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:620 +0x660 fp=0x2912fa8 sp=0x2912f00 pc=0x6bf56c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.main() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x2912fec sp=0x2912fa8 pc=0x6f390 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2912fec sp=0x2912fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 2 gp=0x24027e8 m=nil [force gc (idle), 3 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2448fd4 sp=0x2448fc0 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goparkunlock(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:466 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.forcegchelper() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2448fec sp=0x2448fd4 pc=0x6f7f4 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2448fec sp=0x2448fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.init.6 in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:361 +0x1c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 3 gp=0x2402b48 m=nil [GC sweep wait]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24497c4 sp=0x24497b0 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goparkunlock(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:466 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.bgsweep(0x242e050) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x24497e4 sp=0x24497c4 pc=0x5768c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcenable.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x24497ec sp=0x24497e4 pc=0x46c3c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24497ec sp=0x24497ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.gcenable in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 4 gp=0x2402c68 m=nil [GC scavenge wait]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97746c, 0xffa170, 0xd, 0xa, 0x2) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2449fb4 sp=0x2449fa0 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goparkunlock(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:466 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.(*scavengerState).park(0xffa170) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2449fc8 sp=0x2449fb4 pc=0x54a28 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.bgscavenge(0x242e050) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2449fe4 sp=0x2449fc8 pc=0x5516c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcenable.gowrap2() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2449fec sp=0x2449fe4 pc=0x46be8 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2449fec sp=0x2449fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.gcenable in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 18 gp=0x2502008 m=nil [GOMAXPROCS updater (idle), 3451 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24447a4 sp=0x2444790 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goparkunlock(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:466 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.updateMaxProcsGoroutine() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x24447ec sp=0x24447a4 pc=0x7f174 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24447ec sp=0x24447ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 5 gp=0x2402fc8 m=nil [finalizer wait, 632 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244a788 sp=0x244a774 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.runFinalizers() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x244a7ec sp=0x244a788 pc=0x45888 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244a7ec sp=0x244a7ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.createfing in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 19 gp=0x2502a28 m=nil [cleanup wait, 629 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2444fa4 sp=0x2444f90 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goparkunlock(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:466 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.(*cleanupQueue).dequeue(0xffa2e8) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x2444fc4 sp=0x2444fa4 pc=0x41cb0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.runCleanups() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x2444fec sp=0x2444fc4 pc=0x4284c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2444fec sp=0x2444fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 35 gp=0x2638248 m=nil [IO wait, 634 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97745c, 0x76041010, 0x2, 0x2, 0x5) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2759cf0 sp=0x2759cdc pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.netpollblock(0x76041000, 0x72, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2759d08 sp=0x2759cf0 pc=0x675a0 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.runtime_pollWait(0x76041000, 0x72) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2759d1c sp=0x2759d08 pc=0xa8864 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).wait(0x24fd148, 0x72, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2759d30 sp=0x2759d1c pc=0x136044 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).waitRead(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*FD).Accept(0x24fd130) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2759d78 sp=0x2759d30 pc=0x13a800 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).accept(0x24fd130) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x2759de0 sp=0x2759d78 pc=0x1abc28 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*TCPListener).accept(0x24ae3f0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2759e34 sp=0x2759de0 pc=0x1c3554 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*TCPListener).Accept(0x24ae3f0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x2759e50 sp=0x2759e34 pc=0x1c259c Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*onceCloseListener).Accept(0x246ed20) Jun 01 11:38:59 volumio go-librespot[1599]: :1 +0x34 fp=0x2759e68 sp=0x2759e50 pc=0x367f9c Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Server).Serve(0x25a0378, {0xa3d218, 0x24ae3f0}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x2759f00 sp=0x2759e68 pc=0x341154 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.Serve(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:2971 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*ConcreteApiServer).serve(0x24b8420) Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x2759fe4 sp=0x2759f00 pc=0x6b17bc Jun 01 11:38:59 volumio go-librespot[1599]: main.NewApiServer.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x2759fec sp=0x2759fe4 pc=0x6b04f4 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2759fec sp=0x2759fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by main.NewApiServer in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/api_server.go:332 +0x310 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 27 gp=0x2502d88 m=nil [GC worker (idle)]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x977320, 0x2734400, 0x1c, 0xa, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2458f88 sp=0x2458f74 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcBgMarkWorker(0x28136c0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2458fe4 sp=0x2458f88 pc=0x49f34 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2458fec sp=0x2458fe4 pc=0x49e14 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2458fec sp=0x2458fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 6 gp=0x25cb208 m=nil [GC worker (idle), 180 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x977320, 0x2734600, 0x1c, 0xa, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2869f88 sp=0x2869f74 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcBgMarkWorker(0x28136c0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2869fe4 sp=0x2869f88 pc=0x49f34 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2869fec sp=0x2869fe4 pc=0x49e14 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2869fec sp=0x2869fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 7 gp=0x25cb328 m=nil [GC worker (idle), 1574 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x977320, 0x2734800, 0x1c, 0xa, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2868788 sp=0x2868774 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcBgMarkWorker(0x28136c0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x28687e4 sp=0x2868788 pc=0x49f34 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x28687ec sp=0x28687e4 pc=0x49e14 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x28687ec sp=0x28687ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 8 gp=0x25cb448 m=nil [GC worker (idle)]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x977320, 0x2734a00, 0x1c, 0xa, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2866788 sp=0x2866774 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcBgMarkWorker(0x28136c0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x28667e4 sp=0x2866788 pc=0x49f34 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x28667ec sp=0x28667e4 pc=0x49e14 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x28667ec sp=0x28667ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by runtime.gcBgMarkStartWorkers in goroutine 25 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 28 gp=0x2502fc8 m=nil [chan receive, 3451 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9772f4, 0x26fe138, 0xe, 0x7, 0x2) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26a8f78 sp=0x26a8f64 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.chanrecv(0x26fe100, 0x0, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x26a8fc0 sp=0x26a8f78 pc=0x3414c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.chanrecv1(0x26fe100, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x26a8fd4 sp=0x26a8fc0 pc=0x33bcc Jun 01 11:38:59 volumio go-librespot[1599]: github.com/godbus/dbus/v5.newConn.func1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x26a8fec sp=0x26a8fd4 pc=0x4aa8ec Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26a8fec sp=0x26a8fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 29 gp=0x25030e8 m=nil [IO wait, 3451 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97745c, 0x76040a10, 0x2, 0x2, 0x5) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2916cbc sp=0x2916ca8 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.netpollblock(0x76040a00, 0x72, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2916cd4 sp=0x2916cbc pc=0x675a0 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.runtime_pollWait(0x76040a00, 0x72) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2916ce8 sp=0x2916cd4 pc=0xa8864 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).wait(0x26d20b8, 0x72, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2916cfc sp=0x2916ce8 pc=0x136044 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).waitRead(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*FD).ReadMsg(0x26d20a0, {0x26c41c0, 0x10, 0x10}, {0x24df918, 0x1000, 0x1000}, 0x40000000) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x2916d6c sp=0x2916cfc pc=0x1381bc Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).readMsg(0x26d20a0, {0x26c41c0, 0x10, 0x10}, {0x24df918, 0x1000, 0x1000}, 0x40000000) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x2916dc8 sp=0x2916d6c pc=0x1aa2c8 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*UnixConn).readMsg(0x26d61a8, {0x26c41c0, 0x10, 0x10}, {0x24df918, 0x1000, 0x1000}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x2916e28 sp=0x2916dc8 pc=0x1c9d80 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*UnixConn).ReadMsgUnix(0x26d61a8, {0x26c41c0, 0x10, 0x10}, {0x24df918, 0x1000, 0x1000}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x2916e78 sp=0x2916e28 pc=0x1c820c Jun 01 11:38:59 volumio go-librespot[1599]: github.com/godbus/dbus/v5.(*oobReader).Read(0x24df908, {0x26c41c0, 0x10, 0x10}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x2916ec8 sp=0x2916e78 pc=0x4c2af0 Jun 01 11:38:59 volumio go-librespot[1599]: io.ReadAtLeast({0xa39d58, 0x24df908}, {0x26c41c0, 0x10, 0x10}, 0x10) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2916ef4 sp=0x2916ec8 pc=0xee954 Jun 01 11:38:59 volumio go-librespot[1599]: io.ReadFull(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/io/io.go:354 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x26820c0) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x2916fa0 sp=0x2916ef4 pc=0x4c32c4 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x2590580) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x2916fe4 sp=0x2916fa0 pc=0x4aaef4 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x2916fec sp=0x2916fe4 pc=0x4a8318 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2916fec sp=0x2916fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 84 gp=0x2503688 m=nil [chan receive, 634 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9772f4, 0x24ca1b8, 0xe, 0x7, 0x2) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26acf3c sp=0x26acf28 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.chanrecv(0x24ca180, 0x26acfd0, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x26acf84 sp=0x26acf3c pc=0x3414c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.chanrecv2(0x24ca180, 0x26acfd0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x26acf98 sp=0x26acf84 pc=0x33bf0 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*App).withAppPlayer.func1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:284 +0x68 fp=0x26acfec sp=0x26acf98 pc=0x6bd918 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26acfec sp=0x26acfec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by main.(*App).withAppPlayer in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:281 +0x4f8 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 85 gp=0x25037a8 m=nil [chan receive, 1377 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9772f4, 0x24ca178, 0xe, 0x7, 0x2) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26ab740 sp=0x26ab72c pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.chanrecv(0x24ca140, 0x26a3fe0, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x26ab788 sp=0x26ab740 pc=0x3414c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.chanrecv2(0x24ca140, 0x26a3fe0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x26ab79c sp=0x26ab788 pc=0x33bf0 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*App).withAppPlayer.func2() Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:300 +0x6c fp=0x26ab7ec sp=0x26ab79c pc=0x6bd47c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26ab7ec sp=0x26ab7ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by main.(*App).withAppPlayer in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:297 +0x598 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 86 gp=0x25038c8 m=nil [IO wait, 703 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97745c, 0x76040c10, 0x2, 0x2, 0x5) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26addac sp=0x26add98 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.netpollblock(0x76040c00, 0x72, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x26addc4 sp=0x26addac pc=0x675a0 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.runtime_pollWait(0x76040c00, 0x72) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x26addd8 sp=0x26addc4 pc=0xa8864 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).wait(0x26d2068, 0x72, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x26addec sp=0x26addd8 pc=0x136044 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).waitRead(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*FD).Accept(0x26d2050) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x26ade34 sp=0x26addec pc=0x13a800 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).accept(0x26d2050) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x26ade9c sp=0x26ade34 pc=0x1abc28 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*TCPListener).accept(0x26ae060) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x26adef0 sp=0x26ade9c pc=0x1c3554 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*TCPListener).Accept(0x26ae060) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x26adf0c sp=0x26adef0 pc=0x1c259c Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*onceCloseListener).Accept(0x26d0b60) Jun 01 11:38:59 volumio go-librespot[1599]: :1 +0x34 fp=0x26adf24 sp=0x26adf0c pc=0x367f9c Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Server).Serve(0x26b04d8, {0xa3d218, 0x26ae060}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x26adfbc sp=0x26adf24 pc=0x341154 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.Serve(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:2971 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jun 01 11:38:59 volumio go-librespot[1599]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x26adfec sp=0x26adfbc pc=0x62f538 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26adfec sp=0x26adfec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jun 01 11:38:59 volumio go-librespot[1599]: /src/zeroconf/zeroconf.go:297 +0x160 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 109 gp=0x2639208 m=nil [IO wait, 3451 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97745c, 0x74e72010, 0x2, 0x2, 0x5) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x269f908 sp=0x269f8f4 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.netpollblock(0x74e72000, 0x72, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x269f920 sp=0x269f908 pc=0x675a0 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.runtime_pollWait(0x74e72000, 0x72) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x269f934 sp=0x269f920 pc=0xa8864 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).wait(0x298eba8, 0x72, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x269f948 sp=0x269f934 pc=0x136044 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).waitRead(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*FD).Read(0x298eb90, {0x2743000, 0x1000, 0x1000}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x269f990 sp=0x269f948 pc=0x1371c4 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).Read(0x298eb90, {0x2743000, 0x1000, 0x1000}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x269f9bc sp=0x269f990 pc=0x1a9e78 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*conn).Read(0x2516530, {0x2743000, 0x1000, 0x1000}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x269f9e8 sp=0x269f9bc pc=0x1b967c Jun 01 11:38:59 volumio go-librespot[1599]: io.(*multiReader).Read(0x2568f10, {0x2743000, 0x1000, 0x1000}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x269fa14 sp=0x269f9e8 pc=0xefb88 Jun 01 11:38:59 volumio go-librespot[1599]: bufio.(*Reader).fill(0x270a5d0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x269fa38 sp=0x269fa14 pc=0x2b6c1c Jun 01 11:38:59 volumio go-librespot[1599]: bufio.(*Reader).ReadByte(0x270a5d0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x269fa44 sp=0x269fa38 pc=0x2b7498 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/coder/websocket.readFrameHeader(0x270a5d0, {0x256c1b8, 0x8, 0x8}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x269fa84 sp=0x269fa44 pc=0x372a08 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/coder/websocket.(*Conn).readFrameHeader(0x256c188, {0xa3da00, 0x1005090}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x269fb08 sp=0x269fa84 pc=0x375bd4 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/coder/websocket.(*Conn).readLoop(0x256c188, {0xa3da00, 0x1005090}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x269fbcc sp=0x269fb08 pc=0x375390 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/coder/websocket.(*Conn).reader(0x256c188, {0xa3da00, 0x1005090}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x269fc50 sp=0x269fbcc pc=0x376798 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/coder/websocket.(*Conn).Reader(...) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/coder/websocket.(*Conn).Read(0x256c188, {0xa3da00, 0x1005090}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x269fc78 sp=0x269fc50 pc=0x374944 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x24191e8}, 0x2419088) Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x269fcfc sp=0x269fc78 pc=0x6b2040 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.HandlerFunc.ServeHTTP(0x24323d0, {0xa3d2d8, 0x24191e8}, 0x2419088) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x269fd0c sp=0x269fcfc pc=0x33d7d8 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*ServeMux).ServeHTTP(0x2590400, {0xa3d2d8, 0x24191e8}, 0x2419088) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x269fd4c sp=0x269fd0c pc=0x33f6c8 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x24191e8}, 0x2419088) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x269fd74 sp=0x269fd4c pc=0x6b19b8 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.HandlerFunc.ServeHTTP(0x2410430, {0xa3d2d8, 0x24191e8}, 0x2419088) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x269fd84 sp=0x269fd74 pc=0x33d7d8 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.serverHandler.ServeHTTP({0x25a0378}, {0xa3d2d8, 0x24191e8}, 0x2419088) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x269fda0 sp=0x269fd84 pc=0x35cb44 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*conn).serve(0x263a1e0, {0xa3da38, 0x2428588}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x269ffdc sp=0x269fda0 pc=0x33b894 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Server).Serve.gowrap3() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x269ffec sp=0x269ffdc pc=0x3415cc Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x269ffec sp=0x269ffec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by net/http.(*Server).Serve in goroutine 35 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/server.go:3493 +0x42c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20357041 gp=0x26b2d88 m=nil [select]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x29f46d0 sp=0x29f46bc pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.selectgo(0x29f47d4, 0x29f47a8, 0x0, 0x0, 0x2, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x29f4780 sp=0x29f46d0 pc=0x85000 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).connect.func2() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_unix.go:107 +0x80 fp=0x29f47ec sp=0x29f4780 pc=0x1ab938 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x29f47ec sp=0x29f47ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by net.(*netFD).connect in goroutine 20357274 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_unix.go:106 +0x30c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20357040 gp=0x2679d48 m=nil [select]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x28556d0 sp=0x28556bc pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.selectgo(0x28557d4, 0x28557a8, 0x0, 0x0, 0x2, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2855780 sp=0x28556d0 pc=0x85000 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).connect.func2() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_unix.go:107 +0x80 fp=0x28557ec sp=0x2855780 pc=0x1ab938 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x28557ec sp=0x28557ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by net.(*netFD).connect in goroutine 20357039 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_unix.go:106 +0x30c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20357222 gp=0x2955448 m=nil [select]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x245d604 sp=0x245d5f0 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.selectgo(0x245d764, 0x245d6dc, 0x0, 0x0, 0x2, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x245d6b4 sp=0x245d604 pc=0x85000 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Transport).getConn(0xfa9328, 0x270a0c0, {{}, 0x0, {0x92ae9f, 0x5}, {0x253a030, 0x16}, 0x0}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/transport.go:1523 +0x370 fp=0x245d7b0 sp=0x245d6b4 pc=0x351ccc Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Transport).roundTrip(0xfa9328, 0x26b02c8) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/transport.go:685 +0x978 fp=0x245d89c sp=0x245d7b0 pc=0x34ddb4 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Transport).RoundTrip(0xfa9328, 0x26b02c8) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/roundtrip.go:33 +0x2c fp=0x245d8b4 sp=0x245d89c pc=0x35c310 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.send(0x26b0168, {0xa39338, 0xfa9328}, {0xc27f72aec212086b, 0xbc5f34157ff4, 0xff9f08}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/client.go:259 +0x53c fp=0x245d9e0 sp=0x245d8b4 pc=0x2edc78 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Client).send(0x24910e0, 0x26b0168, {0xc27f72aec212086b, 0xbc5f34157ff4, 0xff9f08}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x245da24 sp=0x245d9e0 pc=0x2ed564 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Client).do(0x24910e0, 0x26b0168) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/client.go:729 +0x9a8 fp=0x245db34 sp=0x245da24 pc=0x2efbcc Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Client).Do(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/client.go:587 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/login5.(*Login5).request(0x35b2500, {0xa3da1c, 0x1005090}, 0x270a030) Jun 01 11:38:59 volumio go-librespot[1599]: /src/login5/login5.go:72 +0x4b8 fp=0x245dc58 sp=0x245db34 pc=0x5afe1c Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/login5.(*Login5).Login(0x35b2500, {0xa3da1c, 0x1005090}, {0xa39c18, 0x270a000}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/login5/login5.go:122 +0x4b8 fp=0x245dcec sp=0x245dc58 pc=0x5b0614 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/session.NewSessionFromOptions.(*Login5).AccessToken.func2({0xa3da1c, 0x1005090}, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /src/login5/login5.go:200 +0x1e4 fp=0x245dd88 sp=0x245dcec pc=0x5b7dc8 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/dealer.(*Dealer).connect(0x2419c38, {0xa3da1c, 0x1005090}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/dealer/dealer.go:82 +0xc8 fp=0x245de1c sp=0x245dd88 pc=0x5a1844 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect(0x2419c38) Jun 01 11:38:59 volumio go-librespot[1599]: /src/dealer/dealer.go:288 +0x2c fp=0x245de54 sp=0x245de1c pc=0x5a2d08 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect-fm() Jun 01 11:38:59 volumio go-librespot[1599]: :1 +0x28 fp=0x245de64 sp=0x245de54 pc=0x5a466c Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x245de70 sp=0x245de64 pc=0x4eb1c8 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x245df14, {0xa3c8c0, 0x2800780}, 0x0, {0x0, 0x0}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x245deec sp=0x245de70 pc=0x4eb644 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x245df68, {0xa3c8c0, 0x2800780}, 0x0, {0x0, 0x0}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x245df1c sp=0x245deec pc=0x4eb180 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.Retry(...) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x2419c38) Jun 01 11:38:59 volumio go-librespot[1599]: /src/dealer/dealer.go:237 +0x688 fp=0x245dfe4 sp=0x245df1c pc=0x5a285c Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/dealer/dealer.go:296 +0x28 fp=0x245dfec sp=0x245dfe4 pc=0x5a2e88 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x245dfec sp=0x245dfec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 20356672 Jun 01 11:38:59 volumio go-librespot[1599]: /src/dealer/dealer.go:296 +0xf8 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20356624 gp=0x274a908 m=nil [select, 255 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2be5930 sp=0x2be591c pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.selectgo(0x2be5f70, 0x2be5b20, 0x0, 0x0, 0xa, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2be59e0 sp=0x2be5930 pc=0x85000 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*AppPlayer).Run(0x2eb2370, {0xa3da1c, 0x1005090}, 0x297c3c0, 0x297c480) Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/player.go:661 +0x368 fp=0x2be5fd4 sp=0x2be59e0 pc=0x6c5950 Jun 01 11:38:59 volumio go-librespot[1599]: main.(*App).withAppPlayer.func2.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:328 +0x48 fp=0x2be5fec sp=0x2be5fd4 pc=0x6bd884 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2be5fec sp=0x2be5fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by main.(*App).withAppPlayer.func2 in goroutine 85 Jun 01 11:38:59 volumio go-librespot[1599]: /src/cmd/daemon/main.go:328 +0x2f0 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20356623 gp=0x279a6c8 m=nil [select, 634 minutes]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2446674 sp=0x2446660 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.selectgo(0x2446780, 0x244674c, 0x0, 0x0, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2446724 sp=0x2446674 pc=0x85000 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x251e640) Jun 01 11:38:59 volumio go-librespot[1599]: /src/player/player.go:215 +0x1f4 fp=0x24467e4 sp=0x2446724 pc=0x582954 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/player/player.go:197 +0x28 fp=0x24467ec sp=0x24467e4 pc=0x58253c Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24467ec sp=0x24467ec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 85 Jun 01 11:38:59 volumio go-librespot[1599]: /src/player/player.go:197 +0x220 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20357034 gp=0x2860368 m=nil [select]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2459984 sp=0x2459970 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.selectgo(0x2459b0c, 0x2459a60, 0x0, 0x0, 0x2, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2459a34 sp=0x2459984 pc=0x85000 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialParallel(0x24f0080, {0xa3da8c, 0x2f06cd0}, {0x2516328, 0x1, 0x1}, {0x2516348, 0x1, 0x1}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:628 +0x324 fp=0x2459b40 sp=0x2459a34 pc=0x1a1c94 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*Dialer).DialContext(0x2522f50, {0xa3da54, 0x270a0f0}, {0x919486, 0x3}, {0x253a030, 0x16}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:578 +0x6b8 fp=0x2459c3c sp=0x2459b40 pc=0x1a15b0 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*Dialer).DialContext-fm({0xa3da54, 0x270a0f0}, {0x919486, 0x3}, {0x253a030, 0x16}) Jun 01 11:38:59 volumio go-librespot[1599]: :1 +0x58 fp=0x2459c6c sp=0x2459c3c pc=0x3691a0 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Transport).dial(0xfa9328, {0xa3da54, 0x270a0f0}, {0x919486, 0x3}, {0x253a030, 0x16}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/transport.go:1278 +0x158 fp=0x2459cac sp=0x2459c6c pc=0x350f60 Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Transport).dialConn(0xfa9328, {0xa3da54, 0x270a0f0}, {{}, 0x0, {0x92ae9f, 0x5}, {0x253a030, 0x16}, 0x0}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/transport.go:1783 +0x6a4 fp=0x2459f60 sp=0x2459cac pc=0x35393c Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Transport).dialConnFor(0xfa9328, 0x2874120) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/transport.go:1618 +0x80 fp=0x2459fc4 sp=0x2459f60 pc=0x35270c Jun 01 11:38:59 volumio go-librespot[1599]: net/http.(*Transport).startDialConnForLocked.func1() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/transport.go:1600 +0x3c fp=0x2459fec sp=0x2459fc4 pc=0x352594 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2459fec sp=0x2459fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by net/http.(*Transport).startDialConnForLocked in goroutine 20357222 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/http/transport.go:1599 +0x134 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20357274 gp=0x2886ea8 m=nil [IO wait]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97745c, 0x74e72214, 0x2, 0x2, 0x5) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x291161c sp=0x2911608 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.netpollblock(0x74e72200, 0x77, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2911634 sp=0x291161c pc=0x675a0 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.runtime_pollWait(0x74e72200, 0x77) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2911648 sp=0x2911634 pc=0xa8864 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).wait(0x2f06ec8, 0x77, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x291165c sp=0x2911648 pc=0x136044 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).waitWrite(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*FD).WaitWrite(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_unix.go:685 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).connect(0x2f06eb0, {0xa3da8c, 0x2f06d70}, {0x0, 0x0}, {0xa38f68, 0x253a210}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_unix.go:130 +0x5cc fp=0x2911748 sp=0x291165c pc=0x1ab510 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).dial(0x2f06eb0, {0xa3da8c, 0x2f06d70}, {0xa3e3cc, 0x0}, {0xa3e3cc, 0x260adc8}, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/sock_posix.go:124 +0x3ec fp=0x29117bc sp=0x2911748 pc=0x1beff0 Jun 01 11:38:59 volumio go-librespot[1599]: net.socket({0xa3da8c, 0x2f06d70}, {0x919486, 0x3}, 0x2, 0x1, 0x0, 0x0, {0xa3e3cc, 0x0}, ...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/sock_posix.go:70 +0x2ac fp=0x2911814 sp=0x29117bc pc=0x1beb28 Jun 01 11:38:59 volumio go-librespot[1599]: net.internetSocket({0xa3da8c, 0x2f06d70}, {0x919486, 0x3}, {0xa3e3cc, 0x0}, {0xa3e3cc, 0x260adc8}, 0x1, 0x0, ...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/ipsock_posix.go:167 +0xc0 fp=0x2911858 sp=0x2911814 pc=0x1b6728 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).doDialTCPProto(0x24f0180, {0xa3da8c, 0x2f06d70}, 0x0, 0x260adc8, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock_posix.go:85 +0xa4 fp=0x29118d4 sp=0x2911858 pc=0x1c30e0 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).doDialTCP(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock_posix.go:75 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialTCP(0x24f0180, {0xa3da8c, 0x2f06d70}, 0x0, 0x260adc8) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock_posix.go:71 +0xb4 fp=0x29118fc sp=0x29118d4 pc=0x1c2fb8 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialSingle(0x24f0180, {0xa3da8c, 0x2f06d70}, {0xa3c298, 0x260adc8}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:721 +0x388 fp=0x291195c sp=0x29118fc pc=0x1a2b04 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialSerial(0x24f0180, {0xa3da8c, 0x2f06d70}, {0x25163b8, 0x1, 0x1}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:686 +0x224 fp=0x2911a30 sp=0x291195c pc=0x1a239c Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialParallel(0x24f0180, {0xa3da8c, 0x2f06d70}, {0x25163b8, 0x1, 0x1}, {0x0, 0x0, 0x0}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:587 +0x290 fp=0x2911b3c sp=0x2911a30 pc=0x1a1c00 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*Dialer).DialContext(0x2911c68, {0xa3da8c, 0x2f06d70}, {0x919486, 0x3}, {0x242ca50, 0x16}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:578 +0x6b8 fp=0x2911c38 sp=0x2911b3c pc=0x1a15b0 Jun 01 11:38:59 volumio go-librespot[1599]: golang.org/x/net/proxy.direct.DialContext(...) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 Jun 01 11:38:59 volumio go-librespot[1599]: golang.org/x/net/proxy.(*direct).DialContext(0x1005090, {0xa3da8c, 0x2f06d70}, {0x919486, 0x3}, {0x242ca50, 0x16}) Jun 01 11:38:59 volumio go-librespot[1599]: :1 +0x74 fp=0x2911ccc sp=0x2911c38 pc=0x4f2fcc Jun 01 11:38:59 volumio go-librespot[1599]: golang.org/x/net/proxy.Dial({0xa3da8c, 0x2f06d70}, {0x919486, 0x3}, {0x242ca50, 0x16}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0xac fp=0x2911d08 sp=0x2911ccc pc=0x4f0e90 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0x2419b88, {0xa3da1c, 0x1005090}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:95 +0x2a8 fp=0x2911d80 sp=0x2911d08 pc=0x4f5950 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0x2419b88, {0xa3da1c, 0x1005090}, 0x270a1e0) Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:205 +0xd4 fp=0x2911de4 sp=0x2911d80 pc=0x4f6a58 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0x2419b88) Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:394 +0xcc fp=0x2911e0c sp=0x2911de4 pc=0x4f8018 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Jun 01 11:38:59 volumio go-librespot[1599]: :1 +0x28 fp=0x2911e1c sp=0x2911e0c pc=0x4fc270 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x2911e28 sp=0x2911e1c pc=0x4eb1c8 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x2911ecc, {0xa3c8c0, 0x2874540}, 0x0, {0x0, 0x0}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x2911ea4 sp=0x2911e28 pc=0x4eb644 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x2911f5c, {0xa3c8c0, 0x2874540}, 0x0, {0x0, 0x0}) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x2911ed4 sp=0x2911ea4 pc=0x4eb180 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/cenkalti/backoff/v4.Retry(...) Jun 01 11:38:59 volumio go-librespot[1599]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x2419b88) Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:335 +0x434 fp=0x2911fe4 sp=0x2911ed4 pc=0x4f7878 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:403 +0x28 fp=0x2911fec sp=0x2911fe4 pc=0x4f81cc Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2911fec sp=0x2911fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 20357011 Jun 01 11:38:59 volumio go-librespot[1599]: /src/ap/ap.go:403 +0x120 Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20356673 gp=0x28af688 m=nil [select]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x275be84 sp=0x275be70 pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.selectgo(0x275bfd4, 0x275bf5c, 0x0, 0x0, 0x2, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x275bf34 sp=0x275be84 pc=0x85000 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x2419c38) Jun 01 11:38:59 volumio go-librespot[1599]: /src/dealer/dealer.go:138 +0x8c fp=0x275bfe4 sp=0x275bf34 pc=0x5a1d90 Jun 01 11:38:59 volumio go-librespot[1599]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jun 01 11:38:59 volumio go-librespot[1599]: /src/dealer/dealer.go:129 +0x28 fp=0x275bfec sp=0x275bfe4 pc=0x5a3cd4 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x275bfec sp=0x275bfec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 20356624 Jun 01 11:38:59 volumio go-librespot[1599]: /src/dealer/dealer.go:129 +0x15c Jun 01 11:38:59 volumio go-librespot[1599]: goroutine 20357039 gp=0x28c3c28 m=nil [IO wait]: Jun 01 11:38:59 volumio go-librespot[1599]: runtime.gopark(0x97745c, 0x76040814, 0x2, 0x2, 0x5) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2457b60 sp=0x2457b4c pc=0xa97c0 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.netpollblock(0x76040800, 0x77, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2457b78 sp=0x2457b60 pc=0x675a0 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.runtime_pollWait(0x76040800, 0x77) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2457b8c sp=0x2457b78 pc=0xa8864 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).wait(0x2f06e28, 0x77, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2457ba0 sp=0x2457b8c pc=0x136044 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*pollDesc).waitWrite(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 Jun 01 11:38:59 volumio go-librespot[1599]: internal/poll.(*FD).WaitWrite(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/internal/poll/fd_unix.go:685 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).connect(0x2f06e10, {0xa3da54, 0x270a300}, {0x0, 0x0}, {0xa38f68, 0x253a1f8}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/fd_unix.go:130 +0x5cc fp=0x2457c8c sp=0x2457ba0 pc=0x1ab510 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*netFD).dial(0x2f06e10, {0xa3da54, 0x270a300}, {0xa3e3cc, 0x0}, {0xa3e3cc, 0x260ad80}, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/sock_posix.go:124 +0x3ec fp=0x2457d00 sp=0x2457c8c pc=0x1beff0 Jun 01 11:38:59 volumio go-librespot[1599]: net.socket({0xa3da54, 0x270a300}, {0x919486, 0x3}, 0x2, 0x1, 0x0, 0x0, {0xa3e3cc, 0x0}, ...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/sock_posix.go:70 +0x2ac fp=0x2457d58 sp=0x2457d00 pc=0x1beb28 Jun 01 11:38:59 volumio go-librespot[1599]: net.internetSocket({0xa3da54, 0x270a300}, {0x919486, 0x3}, {0xa3e3cc, 0x0}, {0xa3e3cc, 0x260ad80}, 0x1, 0x0, ...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/ipsock_posix.go:167 +0xc0 fp=0x2457d9c sp=0x2457d58 pc=0x1b6728 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).doDialTCPProto(0x24f0080, {0xa3da54, 0x270a300}, 0x0, 0x260ad80, 0x0) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock_posix.go:85 +0xa4 fp=0x2457e18 sp=0x2457d9c pc=0x1c30e0 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).doDialTCP(...) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock_posix.go:75 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialTCP(0x24f0080, {0xa3da54, 0x270a300}, 0x0, 0x260ad80) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/tcpsock_posix.go:71 +0xb4 fp=0x2457e40 sp=0x2457e18 pc=0x1c2fb8 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialSingle(0x24f0080, {0xa3da54, 0x270a300}, {0xa3c298, 0x260ad80}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:721 +0x388 fp=0x2457ea0 sp=0x2457e40 pc=0x1a2b04 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialSerial(0x24f0080, {0xa3da54, 0x270a300}, {0x2516328, 0x1, 0x1}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:686 +0x224 fp=0x2457f74 sp=0x2457ea0 pc=0x1a239c Jun 01 11:38:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jun 01 11:38:59 volumio volumio[1097]: info: Connection to go-librespot Websocket closed Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialParallel.func1({0xa3da54, 0x270a300}, 0x1) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:606 +0x6c fp=0x2457fdc sp=0x2457f74 pc=0x1a2050 Jun 01 11:38:59 volumio go-librespot[1599]: net.(*sysDialer).dialParallel.gowrap2() Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:621 +0x40 fp=0x2457fec sp=0x2457fdc pc=0x1a1fb8 Jun 01 11:38:59 volumio go-librespot[1599]: runtime.goexit({}) Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2457fec sp=0x2457fec pc=0xb0874 Jun 01 11:38:59 volumio go-librespot[1599]: created by net.(*sysDialer).dialParallel in goroutine 20357034 Jun 01 11:38:59 volumio go-librespot[1599]: /usr/local/go/src/net/dial.go:621 +0x1e0 Jun 01 11:38:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 11:38:59 volumio go-librespot[1598]: Aborted Jun 01 11:38:59 volumio systemd[1]: go-librespot-daemon.service: Consumed 1h 43min 58.352s CPU time. Jun 01 11:39:00 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:00 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:00 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:39:00 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:39:00 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:00 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:00 volumio volumio[1097]: info: Listing playlists Jun 01 11:39:00 volumio volumio[1097]: info: Listing playlists Jun 01 11:39:02 volumio volumio[1097]: info: Initializing connection to go-librespot Websocket Jun 01 11:39:03 volumio volumio[1097]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 11:39:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jun 01 11:39:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 01 11:39:03 volumio systemd[1]: go-librespot-daemon.service: Consumed 1h 43min 58.352s CPU time. Jun 01 11:39:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 01 11:39:03 volumio go-librespot[6172]: go-librespot daemon starting... Jun 01 11:39:03 volumio go-librespot[6173]: time="2026-06-01T11:39:03+02:00" level=info msg="running go-librespot 0.7.1" Jun 01 11:39:03 volumio go-librespot[6173]: time="2026-06-01T11:39:03+02:00" level=debug msg="app state loaded" Jun 01 11:39:03 volumio go-librespot[6173]: time="2026-06-01T11:39:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 11:39:06 volumio volumio[1097]: info: Initializing connection to go-librespot Websocket Jun 01 11:39:06 volumio go-librespot[6173]: time="2026-06-01T11:39:06+02:00" level=debug msg="new websocket client" Jun 01 11:39:06 volumio volumio[1097]: info: Connection to go-librespot Websocket established Jun 01 11:39:09 volumio volumio[1097]: info: Getting Spotify volume Jun 01 11:39:09 volumio volumio[1097]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 01 11:39:09 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:09 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:09 volumio volumio[1097]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Jun 01 11:39:09 volumio volumio[1097]: SPOTIFY: SPOTIFY VOLUME 2 Jun 01 11:39:09 volumio volumio[1097]: SPOTIFY: VOLUMIO VOLUME 1 Jun 01 11:39:09 volumio volumio[1097]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 01 11:39:10 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:10 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:10 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:39:10 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:39:10 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:10 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:12 volumio nmbd[959]: [2026/06/01 11:39:12.671411, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 01 11:39:12 volumio nmbd[959]: query_name_response: Multiple (2) responses received for a query on subnet 172.16.0.173 for name WORKGROUP<1d>. Jun 01 11:39:12 volumio nmbd[959]: This response was from IP 172.16.0.70, reporting an IP address of 172.16.0.70. Jun 01 11:39:20 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:20 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:20 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:39:20 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:39:20 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:20 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:20 volumio volumio[1097]: info: Listing playlists Jun 01 11:39:20 volumio volumio[1097]: info: Listing playlists Jun 01 11:39:30 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:30 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:30 volumio volumio[1097]: info: [MQTT-Debug] Published full state to: volumio/volumio/status Jun 01 11:39:30 volumio volumio[1097]: info: [MQTT-Debug] Published individual state topics Jun 01 11:39:30 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Jun 01 11:39:30 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Jun 01 11:39:33 volumio go-librespot[6173]: time="2026-06-01T11:39:33+02: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 35.186.224.24:443: i/o timeout (Client.Timeout exceeded while awaiting headers)" Jun 01 11:39:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 11:39:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 11:39:33 volumio volumio[1097]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 11:39:33 volumio volumio[1097]: Error: socket hang up Jun 01 11:39:33 volumio volumio[1097]: at connResetException (node:internal/errors:720:14) Jun 01 11:39:33 volumio volumio[1097]: at Socket.socketOnEnd (node:_http_client:519:23) Jun 01 11:39:33 volumio volumio[1097]: at Socket.emit (node:events:526:35) Jun 01 11:39:33 volumio volumio[1097]: at endReadableNT (node:internal/streams/readable:1376:12) Jun 01 11:39:33 volumio volumio[1097]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jun 01 11:39:33 volumio volumio[1097]: code: 'ECONNRESET', Jun 01 11:39:33 volumio volumio[1097]: response: undefined Jun 01 11:39:33 volumio volumio[1097]: } Jun 01 11:39:33 volumio volumio[1097]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 11:39:36 volumio sudo[6243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 11:38' Jun 01 11:39:36 volumio sudo[6243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"