-- Logs begin at Thu 2019-02-14 03:11:59 MST, end at Sun 2025-10-26 11:14:13 MST. -- Oct 26 11:13:00 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:00 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10,"mute":false,"disableVolumeControl":false} Oct 26 11:13:00 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:00 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:00 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:00 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:00 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:00 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Oct 26 11:13:00 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":92,"dbVolume":-10,"mute":false,"disableVolumeControl":false} Oct 26 11:13:00 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:00 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:00 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:00 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:00 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 26 11:13:00 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Oct 26 11:13:01 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:01 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":93,"dbVolume":-9,"mute":false,"disableVolumeControl":false} Oct 26 11:13:01 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:01 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:01 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:01 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:01 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:01 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 26 11:13:01 minidsp-shd volumio[626]: SPOTIFY: SPOTIFY VOLUME 92 Oct 26 11:13:01 minidsp-shd volumio[626]: SPOTIFY: VOLUMIO VOLUME 93 Oct 26 11:13:01 minidsp-shd volumio[626]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:03 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":94,"dbVolume":-7.5,"mute":false,"disableVolumeControl":false} Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:03 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:03 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:03 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Oct 26 11:13:03 minidsp-shd volumio[626]: SPOTIFY: SPOTIFY VOLUME 92 Oct 26 11:13:03 minidsp-shd volumio[626]: SPOTIFY: VOLUMIO VOLUME 94 Oct 26 11:13:03 minidsp-shd volumio[626]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 26 11:13:03 minidsp-shd volumio[626]: info: Setting Spotify Volume from Volumio: 94 Oct 26 11:13:03 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":94,"dbVolume":-7.5,"mute":false,"disableVolumeControl":false} Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:03 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:03 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 26 11:13:03 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:03 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":95,"dbVolume":-6.5,"mute":false,"disableVolumeControl":false} Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:03 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:03 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:03 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: SPOTIFY VOLUME 94 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: VOLUMIO VOLUME 95 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 26 11:13:04 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:04 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":96,"dbVolume":-5,"mute":false,"disableVolumeControl":false} Oct 26 11:13:04 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:04 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:04 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:04 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:04 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: SPOTIFY VOLUME 94 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: VOLUMIO VOLUME 96 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 26 11:13:04 minidsp-shd volumio[626]: info: Setting Spotify Volume from Volumio: 96 Oct 26 11:13:04 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:04 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":97,"dbVolume":-4,"mute":false,"disableVolumeControl":false} Oct 26 11:13:04 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:04 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:04 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:04 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:04 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: SPOTIFY VOLUME 96 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: VOLUMIO VOLUME 97 Oct 26 11:13:04 minidsp-shd volumio[626]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 26 11:13:05 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:05 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":98,"dbVolume":-2.5,"mute":false,"disableVolumeControl":false} Oct 26 11:13:05 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:05 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:05 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:05 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:05 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:05 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Oct 26 11:13:05 minidsp-shd volumio[626]: SPOTIFY: SPOTIFY VOLUME 96 Oct 26 11:13:05 minidsp-shd volumio[626]: SPOTIFY: VOLUMIO VOLUME 98 Oct 26 11:13:05 minidsp-shd volumio[626]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 26 11:13:05 minidsp-shd volumio[626]: info: Setting Spotify Volume from Volumio: 98 Oct 26 11:13:06 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:06 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":99,"dbVolume":-1.5,"mute":false,"disableVolumeControl":false} Oct 26 11:13:06 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:06 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:06 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:06 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:06 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:06 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 26 11:13:06 minidsp-shd volumio[626]: SPOTIFY: SPOTIFY VOLUME 98 Oct 26 11:13:06 minidsp-shd volumio[626]: SPOTIFY: VOLUMIO VOLUME 99 Oct 26 11:13:06 minidsp-shd volumio[626]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 26 11:13:06 minidsp-shd volumio[626]: SPOTIFY: SETTING SPOTIFY VOLUME 98 Oct 26 11:13:06 minidsp-shd volumio[626]: info: Sending Spotify command with payload to local API: /player/volume Oct 26 11:13:06 minidsp-shd go-librespot[3287]: time="2025-10-26T11:13:06-07:00" level=debug msg="update volume requested to 64224/65535" Oct 26 11:13:07 minidsp-shd go-librespot[3287]: time="2025-10-26T11:13:07-07:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 26 11:13:07 minidsp-shd go-librespot[3287]: time="2025-10-26T11:13:07-07:00" level=trace msg="emitting websocket event: volume" Oct 26 11:13:07 minidsp-shd volumio[626]: SPOTIFY: received: {"type":"volume","data":{"value":98,"max":100}} Oct 26 11:13:07 minidsp-shd volumio[626]: SPOTIFY: RECEIVED SPOTIFY VOLUME 98 Oct 26 11:13:07 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: inputs , setVolume Oct 26 11:13:07 minidsp-shd volumio[626]: info: msSurfaceDial volumioupdatevolume callback: {"vol":98,"dbVolume":-2.5,"mute":false,"disableVolumeControl":false} Oct 26 11:13:07 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:07 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:07 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:07 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:07 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:07 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Oct 26 11:13:15 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 26 11:13:15 minidsp-shd volumio[626]: info: updateDSP function in raat called! Oct 26 11:13:15 minidsp-shd volumio[626]: info: Updating RAAT Signal Path Oct 26 11:13:18 minidsp-shd go-librespot[3287]: time="2025-10-26T11:13:18-07:00" level=trace msg="received accesspoint ping" Oct 26 11:13:18 minidsp-shd go-librespot[3287]: time="2025-10-26T11:13:18-07:00" level=trace msg="received accesspoint pong ack" Oct 26 11:13:18 minidsp-shd go-librespot[3287]: time="2025-10-26T11:13:18-07:00" level=trace msg="sent dealer ping" Oct 26 11:13:18 minidsp-shd go-librespot[3287]: time="2025-10-26T11:13:18-07:00" level=trace msg="received dealer pong" Oct 26 11:13:19 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 26 11:13:19 minidsp-shd volumio[626]: info: updateDSP function in raat called! Oct 26 11:13:19 minidsp-shd volumio[626]: info: Updating RAAT Signal Path Oct 26 11:13:20 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 26 11:13:20 minidsp-shd volumio[626]: info: updateDSP function in raat called! Oct 26 11:13:20 minidsp-shd volumio[626]: info: Updating RAAT Signal Path Oct 26 11:13:33 minidsp-shd go-librespot[3287]: time="2025-10-26T11:13:33-07:00" level=info msg="playback was transferred to MacBook Pro" Oct 26 11:13:33 minidsp-shd go-librespot[3287]: SIGSEGV: segmentation violation Oct 26 11:13:33 minidsp-shd go-librespot[3287]: PC=0xf7e0a8b8 m=5 sigcode=1 addr=0x10 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: signal arrived during cgo execution Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 78 gp=0x2268008 m=5 mp=0x2080008 [syscall]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.cgocall(0x646528, 0x23d0ecc) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/cgocall.go:157 +0x50 fp=0x23d0eb4 sp=0x23d0e9c pc=0x1a9f8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/output._Cfunc_snd_pcm_close(0xf65182e0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: _cgo_gotypes.go:440 +0x38 fp=0x23d0ec8 sp=0x23d0eb4 pc=0x4a0bb8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/output.(*alsaOutput).Close.func1(0x2280200) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/output/driver-alsa.go:566 +0x50 fp=0x23d0ee4 sp=0x23d0ec8 pc=0x4a62b4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/output.(*alsaOutput).Close(0x2280200) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/output/driver-alsa.go:566 +0x9c fp=0x23d0f14 sp=0x23d0ee4 pc=0x4a60ac Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x250bae0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/player/player.go:275 +0xa0c fp=0x23d0fe4 sp=0x23d0f14 pc=0x511048 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/player/player.go:183 +0x28 fp=0x23d0fec sp=0x23d0fe4 pc=0x510418 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x23d0fec sp=0x23d0fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/player/player.go:183 +0x218 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 1 gp=0x2002128 m=nil [select, 2 minutes]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de884, 0x0, 0x9, 0x3, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2059d30 sp=0x2059d1c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.selectgo(0x2059e30, 0x2185dfc, 0x0, 0x0, 0x2, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2059dd0 sp=0x2059d30 pc=0x6e050 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x20db6e0, 0x2059ec0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x2059e74 sp=0x2059dd0 pc=0x5a5260 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*App).withAppPlayer(0x221a280, {0x881c40, 0xd04cf8}, 0x2031580) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:321 +0x554 fp=0x2059f00 sp=0x2059e74 pc=0x63ad2c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*App).Zeroconf(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:170 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.main() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:549 +0x7f4 fp=0x2059fa8 sp=0x2059f00 pc=0x63d2f8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.main() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x2059fec sp=0x2059fa8 pc=0x59d08 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2059fec sp=0x2059fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 2 gp=0x20027e8 m=nil [force gc (idle), 2 minutes]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de850, 0xcd9428, 0x11, 0xa, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044fd4 sp=0x2044fc0 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goparkunlock(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:408 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.forcegchelper() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x2044fec sp=0x2044fd4 pc=0x5a0d4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2044fec sp=0x2044fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by runtime.init.5 in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:314 +0x1c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 18 gp=0x2084248 m=nil [GC sweep wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de850, 0xcd9be0, 0xc, 0x9, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20407c4 sp=0x20407b0 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goparkunlock(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:408 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.bgsweep(0x2090000) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x20407e4 sp=0x20407c4 pc=0x42a20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gcenable.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x20407ec sp=0x20407e4 pc=0x32b40 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20407ec sp=0x20407ec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by runtime.gcenable in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 19 gp=0x2084368 m=nil [GC scavenge wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de850, 0xcda208, 0xd, 0xa, 0x2) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2040fb4 sp=0x2040fa0 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goparkunlock(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:408 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.(*scavengerState).park(0xcda208) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2040fc8 sp=0x2040fb4 pc=0x3fd40 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.bgscavenge(0x2090000) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2040fe4 sp=0x2040fc8 pc=0x40478 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gcenable.gowrap2() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x2040fec sp=0x2040fe4 pc=0x32aec Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2040fec sp=0x2040fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by runtime.gcenable in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 20 gp=0x20846c8 m=nil [finalizer wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de6fc, 0xd04d94, 0x10, 0xa, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x204178c sp=0x2041778 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.runfinq() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x20417ec sp=0x204178c pc=0x3192c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20417ec sp=0x20417ec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by runtime.createfing in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 5 gp=0x2003c28 m=nil [IO wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf7039f08, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2045d28 sp=0x2045d14 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf7039ef8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2045d40 sp=0x2045d28 pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf7039ef8, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2045d54 sp=0x2045d40 pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x2028fb8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2045d68 sp=0x2045d54 pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).Accept(0x2028fa0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2045db0 sp=0x2045d68 pc=0x10bd5c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*netFD).accept(0x2028fa0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2045e18 sp=0x2045db0 pc=0x17b69c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPListener).accept(0x20264b0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2045e30 sp=0x2045e18 pc=0x193ad8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPListener).Accept(0x20264b0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x2045e4c sp=0x2045e30 pc=0x192b44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*onceCloseListener).Accept(0x2294340) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: :1 +0x34 fp=0x2045e64 sp=0x2045e4c pc=0x35fd2c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*Server).Serve(0x228c0a8, {0x881408, 0x20264b0}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x2045efc sp=0x2045e64 pc=0x33e3e8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.Serve(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:2794 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*ConcreteApiServer).serve(0x22044e0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x2045fe4 sp=0x2045efc pc=0x630290 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.NewApiServer.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x2045fec sp=0x2045fe4 pc=0x62efbc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2045fec sp=0x2045fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by main.NewApiServer in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/api_server.go:296 +0x310 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 13 gp=0x2003d48 m=nil [IO wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf7039d28, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x21efe84 sp=0x21efe70 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf7039d18, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x21efe9c sp=0x21efe84 pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf7039d18, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x21efeb0 sp=0x21efe9c pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x2029238, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21efec4 sp=0x21efeb0 pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).RawRead(0x2029220, 0x2210e10) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x21efef0 sp=0x21efec4 pc=0x10c960 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*rawConn).Read(0x2030418, 0x2210e10) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x21eff10 sp=0x21efef0 pc=0x18df00 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x200f240, 0x2210de0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x21eff40 sp=0x21eff10 pc=0x5459fc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2283c88, {0x2300000, 0x10000, 0x10000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x21eff9c sp=0x21eff40 pc=0x5499e4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/grandcat/zeroconf.(*Server).recv4(0x24535c0, 0x2283c80) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x21effe0 sp=0x21eff9c pc=0x5a0274 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x21effec sp=0x21effe0 pc=0x59ff2c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21effec sp=0x21effec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 50 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 14 gp=0x2003e68 m=nil [IO wait, 2 minutes]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf7039c88, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2046e5c sp=0x2046e48 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf7039c78, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2046e74 sp=0x2046e5c pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf7039c78, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2046e88 sp=0x2046e74 pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x2029288, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2046e9c sp=0x2046e88 pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).RawRead(0x2029270, 0x24b3320) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2046ec8 sp=0x2046e9c pc=0x10c960 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*rawConn).Read(0x2030438, 0x24b3320) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x2046ee8 sp=0x2046ec8 pc=0x18df00 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x200f250, 0x24b32f0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2046f18 sp=0x2046ee8 pc=0x5459fc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2283cb8, {0x24c6000, 0x10000, 0x10000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x2046f9c sp=0x2046f18 pc=0x54d36c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/grandcat/zeroconf.(*Server).recv6(0x24535c0, 0x2283cb0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x2046fe0 sp=0x2046f9c pc=0x5a0468 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x2046fec sp=0x2046fe0 pc=0x59fed0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2046fec sp=0x2046fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 50 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 37 gp=0x22b0368 m=nil [GC worker (idle), 2 minutes]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de70c, 0x22afea8, 0x1a, 0xa, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22b8790 sp=0x22b877c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gcBgMarkWorker() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x22b87ec sp=0x22b8790 pc=0x35768 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22b87ec sp=0x22b87ec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 21 gp=0x2084ea8 m=nil [GC worker (idle)]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de70c, 0x20bc450, 0x1a, 0xa, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044790 sp=0x204477c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gcBgMarkWorker() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20447ec sp=0x2044790 pc=0x35768 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20447ec sp=0x20447ec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 38 gp=0x22b0488 m=nil [GC worker (idle)]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de70c, 0x22afec0, 0x1a, 0xa, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22b8f90 sp=0x22b8f7c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gcBgMarkWorker() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x22b8fec sp=0x22b8f90 pc=0x35768 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22b8fec sp=0x22b8fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 22 gp=0x2084fc8 m=nil [GC worker (idle)]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de70c, 0x20bc468, 0x1a, 0xa, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2041f90 sp=0x2041f7c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gcBgMarkWorker() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2041fec sp=0x2041f90 pc=0x35768 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2041fec sp=0x2041fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 80 gp=0x22685a8 m=nil [select]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de884, 0x0, 0x9, 0x3, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2047ea8 sp=0x2047e94 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.selectgo(0x2047fa4, 0x2047f6c, 0x0, 0x0, 0x5, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2047f48 sp=0x2047ea8 pc=0x6e050 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x24e23c8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2047fe4 sp=0x2047f48 pc=0x471910 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.newConn.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2047fec sp=0x2047fe4 pc=0x471504 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2047fec sp=0x2047fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by nhooyr.io/websocket.newConn in goroutine 79 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 52 gp=0x22686c8 m=nil [chan receive]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de6e0, 0x22340b0, 0xe, 0x7, 0x2) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22b7748 sp=0x22b7734 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.chanrecv(0x2234080, 0x22b77d0, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x22b7784 sp=0x22b7748 pc=0x1d250 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.chanrecv2(0x2234080, 0x22b77d0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x22b7798 sp=0x22b7784 pc=0x1cd94 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*App).withAppPlayer.func1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:268 +0x68 fp=0x22b77ec sp=0x22b7798 pc=0x63b96c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22b77ec sp=0x22b77ec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by main.(*App).withAppPlayer in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:265 +0x464 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 53 gp=0x22687e8 m=nil [chan receive, 2 minutes]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de6e0, 0x2234070, 0xe, 0x7, 0x2) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22b7f40 sp=0x22b7f2c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.chanrecv(0x2234040, 0x22b7fe0, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x22b7f7c sp=0x22b7f40 pc=0x1d250 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.chanrecv2(0x2234040, 0x22b7fe0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x22b7f90 sp=0x22b7f7c pc=0x1cd94 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*App).withAppPlayer.func2() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:284 +0x6c fp=0x22b7fec sp=0x22b7f90 pc=0x63b508 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22b7fec sp=0x22b7fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by main.(*App).withAppPlayer in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:281 +0x504 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 54 gp=0x2268908 m=nil [IO wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf7039dc8, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22b95e8 sp=0x22b95d4 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf7039db8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x22b9600 sp=0x22b95e8 pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf7039db8, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x22b9614 sp=0x22b9600 pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x20291e8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x22b9628 sp=0x22b9614 pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).Accept(0x20291d0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x22b9670 sp=0x22b9628 pc=0x10bd5c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*netFD).accept(0x20291d0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x22b96d8 sp=0x22b9670 pc=0x17b69c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPListener).accept(0x21d9cf8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x22b96f0 sp=0x22b96d8 pc=0x193ad8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPListener).Accept(0x21d9cf8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x22b970c sp=0x22b96f0 pc=0x192b44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*onceCloseListener).Accept(0x240a080) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: :1 +0x34 fp=0x22b9724 sp=0x22b970c pc=0x35fd2c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*Server).Serve(0x247a0a8, {0x881408, 0x21d9cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x22b97bc sp=0x22b9724 pc=0x33e3e8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.Serve(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:2794 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x22b97ec sp=0x22b97bc pc=0x5a535c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22b97ec sp=0x22b97ec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/zeroconf/zeroconf.go:280 +0x160 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 55 gp=0x2268b48 m=nil [IO wait, 2 minutes]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf7039be8, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x21eb87c sp=0x21eb868 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf7039bd8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x21eb894 sp=0x21eb87c pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf7039bd8, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x21eb8a8 sp=0x21eb894 pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x20292d8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21eb8bc sp=0x21eb8a8 pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).Read(0x20292c0, {0x2240000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x21eb904 sp=0x21eb8bc pc=0x1086dc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*netFD).Read(0x20292c0, {0x2240000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x21eb930 sp=0x21eb904 pc=0x1797a8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*conn).Read(0x2030658, {0x2240000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x21eb95c sp=0x21eb930 pc=0x189cc4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPConn).Read(0x2030658, {0x2240000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: :1 +0x44 fp=0x21eb97c sp=0x21eb95c pc=0x19cf18 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: io.(*multiReader).Read(0x23922c0, {0x2240000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x21eb9a8 sp=0x21eb97c pc=0x102700 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bufio.(*Reader).fill(0x22104b0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x21eb9cc sp=0x21eb9a8 pc=0x2baf8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bufio.(*Reader).ReadByte(0x22104b0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x21eb9d8 sp=0x21eb9cc pc=0x2bb810 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.readFrameHeader(0x22104b0, {0x21743f8, 0x8, 0x8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x21eba18 sp=0x21eb9d8 pc=0x474a64 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x21743c8, {0x881c20, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x21ebad8 sp=0x21eba18 pc=0x4776c8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).readLoop(0x21743c8, {0x881c20, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x21ebb9c sp=0x21ebad8 pc=0x4771b0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).reader(0x21743c8, {0x881c20, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x21ebc28 sp=0x21ebb9c pc=0x4783c4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).Reader(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).Read(0x21743c8, {0x881c20, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x21ebc50 sp=0x21ebc28 pc=0x476a94 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*ConcreteApiServer).serve.func17({0x88153c, 0x20ae328}, 0x20ae0a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x21ebccc sp=0x21ebc50 pc=0x630b60 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.HandlerFunc.ServeHTTP(0x228a180, {0x88153c, 0x20ae328}, 0x20ae0a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x21ebcdc sp=0x21ebccc pc=0x33a9cc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*ServeMux).ServeHTTP(0x2280000, {0x88153c, 0x20ae328}, 0x20ae0a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x21ebd18 sp=0x21ebcdc pc=0x33c82c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x88153c, 0x20ae328}, 0x20ae0a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x21ebd40 sp=0x21ebd18 pc=0x630560 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.HandlerFunc.ServeHTTP(0x2284160, {0x88153c, 0x20ae328}, 0x20ae0a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x21ebd50 sp=0x21ebd40 pc=0x33a9cc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.serverHandler.ServeHTTP({0x228c0a8}, {0x88153c, 0x20ae328}, 0x20ae0a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x21ebd6c sp=0x21ebd50 pc=0x33dfbc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*conn).serve(0x20db740, {0x881c60, 0x2296180}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x21ebfdc sp=0x21ebd6c pc=0x33920c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*Server).Serve.gowrap3() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x21ebfec sp=0x21ebfdc pc=0x33e860 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21ebfec sp=0x21ebfec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by net/http.(*Server).Serve in goroutine 5 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/server.go:3285 +0x468 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 24 gp=0x22b0248 m=nil [select]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de884, 0x0, 0x9, 0x3, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22bbea8 sp=0x22bbe94 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.selectgo(0x22bbfa4, 0x22bbf6c, 0x0, 0x0, 0x5, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x22bbf48 sp=0x22bbea8 pc=0x6e050 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x21743c8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x22bbfe4 sp=0x22bbf48 pc=0x471910 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.newConn.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x22bbfec sp=0x22bbfe4 pc=0x471504 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22bbfec sp=0x22bbfec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by nhooyr.io/websocket.newConn in goroutine 55 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 81 gp=0x22b05a8 m=nil [IO wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf7039a08, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x23cdd14 sp=0x23cdd00 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf70399f8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x23cdd2c sp=0x23cdd14 pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf70399f8, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x23cdd40 sp=0x23cdd2c pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x2242ab8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x23cdd54 sp=0x23cdd40 pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).Read(0x2242aa0, {0x2508f8c, 0x3, 0x3}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x23cdd9c sp=0x23cdd54 pc=0x1086dc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*netFD).Read(0x2242aa0, {0x2508f8c, 0x3, 0x3}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x23cddc8 sp=0x23cdd9c pc=0x1797a8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*conn).Read(0x220e768, {0x2508f8c, 0x3, 0x3}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x23cddf4 sp=0x23cddc8 pc=0x189cc4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPConn).Read(0x220e768, {0x2508f8c, 0x3, 0x3}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: :1 +0x44 fp=0x23cde14 sp=0x23cddf4 pc=0x19cf18 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: io.ReadAtLeast({0x87cf98, 0x220e768}, {0x2508f8c, 0x3, 0x3}, 0x3) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x23cde40 sp=0x23cde14 pc=0x101498 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: io.ReadFull(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/io/io.go:354 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x2207940, {0x881c40, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/ap/shannon.go:124 +0x3a0 fp=0x23cdf04 sp=0x23cde40 pc=0x48cccc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x2203298) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/ap/ap.go:276 +0x68 fp=0x23cdfe4 sp=0x23cdf04 pc=0x489508 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/ap/ap.go:260 +0x28 fp=0x23cdfec sp=0x23cdfe4 pc=0x489474 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x23cdfec sp=0x23cdfec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 79 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/ap/ap.go:260 +0x98 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 79 gp=0x2003568 m=nil [chan receive]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de6e0, 0x2207070, 0xe, 0x7, 0x2) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x23c7820 sp=0x23c780c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.chanrecv(0x2207040, 0x0, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x23c785c sp=0x23c7820 pc=0x1d250 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.chanrecv1(0x2207040, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x23c7870 sp=0x23c785c pc=0x1cd70 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/player.(*Player).Stop(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/player/player.go:392 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*AppPlayer).handleDealerMessage(0x2242140, {0x881c40, 0xd04cf8}, {{0x23aaae0, 0x1d}, 0x23ed5c0, {0x23f0000, 0x1a4b, 0x1b00}}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/player.go:125 +0x3d0 fp=0x23c78e4 sp=0x23c7870 pc=0x63db78 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*AppPlayer).Run(0x2242140, {0x881c40, 0xd04cf8}, 0x2540100) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/player.go:598 +0x5d0 fp=0x23c7fd8 sp=0x23c78e4 pc=0x642cf4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: main.(*App).withAppPlayer.func3.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:358 +0x40 fp=0x23c7fec sp=0x23c7fd8 pc=0x63b470 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x23c7fec sp=0x23c7fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by main.(*App).withAppPlayer.func3 in goroutine 1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/cmd/daemon/main.go:358 +0x584 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 84 gp=0x2268fc8 m=nil [select]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de884, 0x0, 0x9, 0x3, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2056e98 sp=0x2056e84 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.selectgo(0x2056fd4, 0x2056f60, 0x0, 0x0, 0x2, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2056f38 sp=0x2056e98 pc=0x6e050 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x2203558) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/dealer/dealer.go:138 +0x8c fp=0x2056fe4 sp=0x2056f38 pc=0x52eab4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/dealer/dealer.go:129 +0x28 fp=0x2056fec sp=0x2056fe4 pc=0x5309c0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2056fec sp=0x2056fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 79 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/dealer/dealer.go:129 +0x15c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 83 gp=0x22690e8 m=nil [IO wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf70398c8, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x23ce940 sp=0x23ce92c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf70398b8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x23ce958 sp=0x23ce940 pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf70398b8, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x23ce96c sp=0x23ce958 pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x22fa9c8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x23ce980 sp=0x23ce96c pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).Read(0x22fa9b0, {0x23b0000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x23ce9c8 sp=0x23ce980 pc=0x1086dc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*netFD).Read(0x22fa9b0, {0x23b0000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x23ce9f4 sp=0x23ce9c8 pc=0x1797a8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*conn).Read(0x2030b38, {0x23b0000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x23cea20 sp=0x23ce9f4 pc=0x189cc4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPConn).Read(0x2030b38, {0x23b0000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: :1 +0x44 fp=0x23cea40 sp=0x23cea20 pc=0x19cf18 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*atLeastReader).Read(0x248e460, {0x23b0000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x23cea6c sp=0x23cea40 pc=0x279858 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bytes.(*Buffer).ReadFrom(0x22295cc, {0x87ddc4, 0x248e460}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x23ceaa8 sp=0x23cea6c pc=0x136da8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readFromUntil(0x2229448, {0x87cf98, 0x2030b38}, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x23cead0 sp=0x23ceaa8 pc=0x279ab4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readRecordOrCCS(0x2229448, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x23cec34 sp=0x23cead0 pc=0x2771b4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readRecord(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:588 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).Read(0x2229448, {0x2533000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x23cec64 sp=0x23cec34 pc=0x27d024 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*readWriteCloserBody).Read(0x2393710, {0x2533000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/transport.go:2429 +0xec fp=0x23cec84 sp=0x23cec64 pc=0x354cd8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bufio.(*Reader).fill(0x2511470) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x23ceca8 sp=0x23cec84 pc=0x2baf8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bufio.(*Reader).ReadByte(0x2511470) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x23cecb4 sp=0x23ceca8 pc=0x2bb810 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.readFrameHeader(0x2511470, {0x24e23f8, 0x8, 0x8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x23cecf4 sp=0x23cecb4 pc=0x474a64 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x24e23c8, {0x881c20, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x23cedb4 sp=0x23cecf4 pc=0x4776c8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).readLoop(0x24e23c8, {0x881c20, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x23cee78 sp=0x23cedb4 pc=0x4771b0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).reader(0x24e23c8, {0x881c20, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x23cef04 sp=0x23cee78 pc=0x4783c4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).Reader(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: nhooyr.io/websocket.(*Conn).Read(0x24e23c8, {0x881c20, 0xd04cf8}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x23cef2c sp=0x23cef04 pc=0x476a94 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x2203558) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/dealer/dealer.go:188 +0x58 fp=0x23cefe4 sp=0x23cef2c pc=0x52ef3c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/dealer/dealer.go:125 +0x28 fp=0x23cefec sp=0x23cefe4 pc=0x530a14 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x23cefec sp=0x23cefec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 79 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/dealer/dealer.go:125 +0x98 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 114 gp=0x20037a8 m=nil [select]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de884, 0x0, 0x9, 0x3, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20426b8 sp=0x20426a4 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.selectgo(0x20427d4, 0x204277c, 0x0, 0x0, 0x2, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2042758 sp=0x20426b8 pc=0x6e050 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x2203298) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/ap/ap.go:355 +0x8c fp=0x20427e4 sp=0x2042758 pc=0x489d28 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/ap/ap.go:264 +0x28 fp=0x20427ec sp=0x20427e4 pc=0x489420 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20427ec sp=0x20427ec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 79 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/ap/ap.go:264 +0x15c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 123 gp=0x2562248 m=nil [IO wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf7039b48, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2054ad4 sp=0x2054ac0 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf7039b38, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2054aec sp=0x2054ad4 pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf7039b38, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2054b00 sp=0x2054aec pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x22a5968, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2054b14 sp=0x2054b00 pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).Read(0x22a5950, {0x23dd000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2054b5c sp=0x2054b14 pc=0x1086dc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*netFD).Read(0x22a5950, {0x23dd000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2054b88 sp=0x2054b5c pc=0x1797a8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*conn).Read(0x2030f58, {0x23dd000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2054bb4 sp=0x2054b88 pc=0x189cc4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPConn).Read(0x2030f58, {0x23dd000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: :1 +0x44 fp=0x2054bd4 sp=0x2054bb4 pc=0x19cf18 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*atLeastReader).Read(0x238ed30, {0x23dd000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2054c00 sp=0x2054bd4 pc=0x279858 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bytes.(*Buffer).ReadFrom(0x2229a4c, {0x87ddc4, 0x238ed30}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2054c3c sp=0x2054c00 pc=0x136da8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readFromUntil(0x22298c8, {0x87cf98, 0x2030f58}, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2054c64 sp=0x2054c3c pc=0x279ab4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readRecordOrCCS(0x22298c8, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2054dc8 sp=0x2054c64 pc=0x2771b4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readRecord(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:588 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).Read(0x22298c8, {0x21f0000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2054df8 sp=0x2054dc8 pc=0x27d024 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bufio.(*Reader).Read(0x2310840, {0x20c4f24, 0x9, 0x9}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x2054e1c sp=0x2054df8 pc=0x2bb618 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: io.ReadAtLeast({0x87d18c, 0x2310840}, {0x20c4f24, 0x9, 0x9}, 0x9) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2054e48 sp=0x2054e1c pc=0x101498 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: io.ReadFull(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/io/io.go:354 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.http2readFrameHeader({0x20c4f24, 0x9, 0x9}, {0x87d18c, 0x2310840}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2054e70 sp=0x2054e48 pc=0x2f4514 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*http2Framer).ReadFrame(0x20c4f00) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2054eec sp=0x2054e70 pc=0x2f4cac Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*http2clientConnReadLoop).run(0x2054fdc) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2054fa4 sp=0x2054eec pc=0x3194c8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*http2ClientConn).readLoop(0x21fc108) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2054fe4 sp=0x2054fa4 pc=0x3189bc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*http2Transport).newClientConn.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2054fec sp=0x2054fe4 pc=0x311a08 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2054fec sp=0x2054fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by net/http.(*http2Transport).newClientConn in goroutine 122 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 105 gp=0x23fed88 m=nil [select, 2 minutes]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de884, 0x0, 0x9, 0x3, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x23cfcb0 sp=0x23cfc9c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.selectgo(0x23cfe14, 0x23cfd80, 0x0, 0x0, 0x3, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x23cfd50 sp=0x23cfcb0 pc=0x6e050 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).recvLoop(0x24fb540) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/audio/provider.go:63 +0x118 fp=0x23cffe4 sp=0x23cfd50 pc=0x491518 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/audio/provider.go:53 +0x28 fp=0x23cffec sp=0x23cffe4 pc=0x492264 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x23cffec sp=0x23cffec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 79 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /src/audio/provider.go:53 +0x60 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 49 gp=0x25625a8 m=nil [IO wait]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de840, 0xf7039508, 0x2, 0x2, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2186afc sp=0x2186ae8 pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpollblock(0xf70394f8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2186b14 sp=0x2186afc pc=0x51aa4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.runtime_pollWait(0xf70394f8, 0x72) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2186b28 sp=0x2186b14 pc=0x8c680 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).wait(0x25714b8, 0x72, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2186b3c sp=0x2186b28 pc=0x1074f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*pollDesc).waitRead(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: internal/poll.(*FD).Read(0x25714a0, {0x2534000, 0xa000, 0xa000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2186b84 sp=0x2186b3c pc=0x1086dc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*netFD).Read(0x25714a0, {0x2534000, 0xa000, 0xa000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2186bb0 sp=0x2186b84 pc=0x1797a8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*conn).Read(0x220e2d8, {0x2534000, 0xa000, 0xa000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2186bdc sp=0x2186bb0 pc=0x189cc4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net.(*TCPConn).Read(0x220e2d8, {0x2534000, 0xa000, 0xa000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: :1 +0x44 fp=0x2186bfc sp=0x2186bdc pc=0x19cf18 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*atLeastReader).Read(0x236d090, {0x2534000, 0xa000, 0xa000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2186c28 sp=0x2186bfc pc=0x279858 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bytes.(*Buffer).ReadFrom(0x20c084c, {0x87ddc4, 0x236d090}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2186c64 sp=0x2186c28 pc=0x136da8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readFromUntil(0x20c06c8, {0x87cf98, 0x220e2d8}, 0x5) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2186c8c sp=0x2186c64 pc=0x279ab4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readRecordOrCCS(0x20c06c8, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2186df0 sp=0x2186c8c pc=0x2771b4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).readRecord(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:588 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: crypto/tls.(*Conn).Read(0x20c06c8, {0x222e000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2186e20 sp=0x2186df0 pc=0x27d024 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*persistConn).Read(0x2202218, {0x222e000, 0x1000, 0x1000}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x2186e54 sp=0x2186e20 pc=0x3527a4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bufio.(*Reader).fill(0x2211470) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x2186e78 sp=0x2186e54 pc=0x2baf8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: bufio.(*Reader).Peek(0x2211470, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x2186e84 sp=0x2186e78 pc=0x2bb110 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*persistConn).readLoop(0x2202218) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x2186fe4 sp=0x2186e84 pc=0x353334 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*Transport).dialConn.gowrap2() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x2186fec sp=0x2186fe4 pc=0x351c54 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2186fec sp=0x2186fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by net/http.(*Transport).dialConn in goroutine 137 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 162 gp=0x23a2488 m=nil [select, 1 minutes]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopark(0x7de884, 0x0, 0x9, 0x3, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x21e9eb0 sp=0x21e9e9c pc=0x5a298 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.selectgo(0x21e9fac, 0x21e9f78, 0x0, 0x0, 0x2, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x21e9f50 sp=0x21e9eb0 pc=0x6e050 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*persistConn).writeLoop(0x2202218) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x21e9fe4 sp=0x21e9f50 pc=0x354df4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: net/http.(*Transport).dialConn.gowrap3() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x21e9fec sp=0x21e9fe4 pc=0x351c00 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goexit({}) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21e9fec sp=0x21e9fec pc=0x90f44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: created by net/http.(*Transport).dialConn in goroutine 137 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: trap 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: error 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: oldmask 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r0 0xf6517700 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r1 0x646528 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r2 0x130 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r3 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r4 0xf6510b70 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r5 0xf6510b70 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r6 0xf6510ae0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r7 0x2272400 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r8 0x2080008 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r9 0x23d0f84 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r10 0x2084128 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fp 0x8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ip 0xf7e4b5b8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: sp 0xf5cfddc8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: lr 0xf7e099df Oct 26 11:13:33 minidsp-shd go-librespot[3287]: pc 0xf7e0a8b8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: cpsr 0x60070030 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fault 0x10 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ----- Oct 26 11:13:33 minidsp-shd go-librespot[3287]: SIGQUIT: quit Oct 26 11:13:33 minidsp-shd go-librespot[3287]: PC=0x91f8c m=0 sigcode=0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 0 gp=0xcda478 m=0 mp=0xcdad20 [idle]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futex(0xcdade8, 0x80, 0x0, 0x0, 0x0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xff949204 sp=0xff949204 pc=0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futexsleep(0xcdade8, 0x0, 0xffffffffffffffff) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xff94922c sp=0xff949204 pc=0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.notesleep(0xcdade8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xff94924c sp=0xff94922c pc=0x22720 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mPark(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:1761 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.stopm() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xff949260 sp=0xff94924c pc=0x5ef04 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.findRunnable() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xff94930c sp=0xff949260 pc=0x60ba4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.schedule() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xff949328 sp=0xff94930c pc=0x620f4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.park_m(0x22685a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xff949348 sp=0xff949328 pc=0x6296c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mcall(0x87b5e0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xff949350 sp=0xff949348 pc=0x8f278 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: trap 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: error 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: oldmask 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r0 0xcdade8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r1 0x80 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r2 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r3 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r4 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r5 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r6 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r7 0xf0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r8 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r9 0xcdca20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r10 0xcda478 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fp 0x6 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ip 0xd871ac2e Oct 26 11:13:33 minidsp-shd go-librespot[3287]: sp 0xff949204 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: lr 0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: pc 0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: cpsr 0xa08d0010 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fault 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ----- Oct 26 11:13:33 minidsp-shd go-librespot[3287]: SIGQUIT: quit Oct 26 11:13:33 minidsp-shd go-librespot[3287]: PC=0x91f90 m=2 sigcode=0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 0 gp=0x20026c8 m=2 mp=0x2048588 [idle]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futex(0xcdcaac, 0x80, 0x0, 0xf7874d60, 0x0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/sys_linux_arm.s:417 +0x20 fp=0xf7874d40 sp=0xf7874d40 pc=0x91f90 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futexsleep(0xcdcaac, 0x0, 0x3386f1e70) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/os_linux.go:75 +0x178 fp=0xf7874d68 sp=0xf7874d40 pc=0x52b74 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.notetsleep_internal(0xcdcaac, 0x3386f1e70) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/lock_futex.go:212 +0x124 fp=0xf7874d90 sp=0xf7874d68 pc=0x228a4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.notetsleep(0xcdcaac, 0x3386f1e70) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/lock_futex.go:235 +0x48 fp=0xf7874da4 sp=0xf7874d90 pc=0x22a44 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.sysmon() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:6001 +0x2e0 fp=0xf7874e00 sp=0xf7874da4 pc=0x679f0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mstart1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:1729 +0x7c fp=0xf7874e10 sp=0xf7874e00 pc=0x5d334 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mstart0() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:1686 +0x7c fp=0xf7874e24 sp=0xf7874e10 pc=0x5d2a8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mstart() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:210 +0x8 fp=0xf7874e28 sp=0xf7874e24 pc=0x8f218 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: trap 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: error 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: oldmask 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r0 0xfffffffc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r1 0x80 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r2 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r3 0xf7874d60 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r4 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r5 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r6 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r7 0xf0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r8 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r9 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r10 0x20026c8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fp 0x3b9aca00 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ip 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: sp 0xf7874d40 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: lr 0x52b74 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: pc 0x91f90 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: cpsr 0x60870010 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fault 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ----- Oct 26 11:13:33 minidsp-shd go-librespot[3287]: SIGQUIT: quit Oct 26 11:13:33 minidsp-shd go-librespot[3287]: PC=0x91f8c m=3 sigcode=0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 0 gp=0x2002a28 m=3 mp=0x2048b08 [idle]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futex(0x2048bd0, 0x80, 0x0, 0x0, 0x0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf6efecc4 sp=0xf6efecc4 pc=0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futexsleep(0x2048bd0, 0x0, 0xffffffffffffffff) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf6efecec sp=0xf6efecc4 pc=0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.notesleep(0x2048bd0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf6efed0c sp=0xf6efecec pc=0x22720 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mPark(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:1761 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.stopm() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf6efed20 sp=0xf6efed0c pc=0x5ef04 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.findRunnable() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf6efedcc sp=0xf6efed20 pc=0x60ba4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.schedule() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf6efede8 sp=0xf6efedcc pc=0x620f4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.park_m(0x22685a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xf6efee08 sp=0xf6efede8 pc=0x6296c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mcall(0xf6eff450) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf6efee10 sp=0xf6efee08 pc=0x8f278 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: trap 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: error 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: oldmask 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r0 0x2048bd0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r1 0x80 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r2 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r3 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r4 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r5 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r6 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r7 0xf0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r8 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r9 0xcdca20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r10 0x2002a28 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fp 0x6 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ip 0xd88da995 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: sp 0xf6efecc4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: lr 0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: pc 0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: cpsr 0xa0800010 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fault 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ----- Oct 26 11:13:33 minidsp-shd go-librespot[3287]: SIGQUIT: quit Oct 26 11:13:33 minidsp-shd go-librespot[3287]: PC=0x187fc m=4 sigcode=0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 0 gp=0x2002c68 m=4 mp=0x2049088 [idle]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime/internal/syscall.Syscall6(0x15a, 0x4, 0xf64fe500, 0x80, 0x360b, 0x0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/internal/syscall/asm_linux_arm.s:17 +0x20 fp=0xf64fe484 sp=0xf64fe484 pc=0x187fc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime/internal/syscall.EpollWait(0x4, {0xf64fe500, 0x80, 0x80}, 0x80, 0x360b) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/internal/syscall/syscall_linux.go:55 +0x60 fp=0xf64fe4b4 sp=0xf64fe484 pc=0x18754 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.netpoll(0x338a8063b) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/netpoll_epoll.go:118 +0x17c fp=0xf64fed20 sp=0xf64fe4b4 pc=0x52698 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.findRunnable() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3470 +0xbbc fp=0xf64fedcc sp=0xf64fed20 pc=0x6095c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.schedule() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf64fede8 sp=0xf64fedcc pc=0x620f4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.park_m(0x2003568) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xf64fee08 sp=0xf64fede8 pc=0x6296c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mcall(0xf64ff450) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf64fee10 sp=0xf64fee08 pc=0x8f278 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: trap 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: error 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: oldmask 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r0 0xfffffffc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r1 0xf64fe500 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r2 0x80 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r3 0x360b Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r4 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r5 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r6 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r7 0x15a Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r8 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r9 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r10 0x2002c68 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fp 0xca6414 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ip 0xd857b52f Oct 26 11:13:33 minidsp-shd go-librespot[3287]: sp 0xf64fe484 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: lr 0x18754 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: pc 0x187fc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: cpsr 0x20800010 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fault 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ----- Oct 26 11:13:33 minidsp-shd go-librespot[3287]: SIGQUIT: quit Oct 26 11:13:33 minidsp-shd go-librespot[3287]: PC=0x91f8c m=6 sigcode=0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 0 gp=0x20845a8 m=6 mp=0x2080588 [idle]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futex(0xd05554, 0x80, 0x0, 0x0, 0x0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf50fedac sp=0xf50fedac pc=0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futexsleep(0xd05554, 0x0, 0xffffffffffffffff) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf50fedd4 sp=0xf50fedac pc=0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.notesleep(0xd05554) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf50fedf4 sp=0xf50fedd4 pc=0x22720 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.templateThread() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:2760 +0x90 fp=0xf50fee00 sp=0xf50fedf4 pc=0x5edbc Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mstart1() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:1729 +0x7c fp=0xf50fee10 sp=0xf50fee00 pc=0x5d334 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mstart0() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:1686 +0x7c fp=0xf50fee24 sp=0xf50fee10 pc=0x5d2a8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mstart() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:210 +0x8 fp=0xf50fee28 sp=0xf50fee24 pc=0x8f218 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: trap 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: error 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: oldmask 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r0 0xd05554 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r1 0x80 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r2 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r3 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r4 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r5 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r6 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r7 0xf0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r8 0xf50ff450 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r9 0xf50ff4c0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r10 0x20845a8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fp 0x6 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ip 0x7 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: sp 0xf50fedac Oct 26 11:13:33 minidsp-shd go-librespot[3287]: lr 0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: pc 0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: cpsr 0xa08e0010 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fault 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ----- Oct 26 11:13:33 minidsp-shd go-librespot[3287]: SIGQUIT: quit Oct 26 11:13:33 minidsp-shd go-librespot[3287]: PC=0x91f8c m=7 sigcode=0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 0 gp=0x2085208 m=7 mp=0x2082688 [idle]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futex(0x2082750, 0x80, 0x0, 0x0, 0x0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf46fecc4 sp=0xf46fecc4 pc=0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futexsleep(0x2082750, 0x0, 0xffffffffffffffff) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf46fecec sp=0xf46fecc4 pc=0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.notesleep(0x2082750) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf46fed0c sp=0xf46fecec pc=0x22720 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mPark(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:1761 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.stopm() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf46fed20 sp=0xf46fed0c pc=0x5ef04 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.findRunnable() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf46fedcc sp=0xf46fed20 pc=0x60ba4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.schedule() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf46fede8 sp=0xf46fedcc pc=0x620f4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.park_m(0x22685a8) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xf46fee08 sp=0xf46fede8 pc=0x6296c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mcall(0xf46ff450) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf46fee10 sp=0xf46fee08 pc=0x8f278 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: trap 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: error 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: oldmask 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r0 0x2082750 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r1 0x80 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r2 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r3 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r4 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r5 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r6 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r7 0xf0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r8 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r9 0xcdca20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r10 0x2085208 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fp 0x6 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ip 0x8f9d4ca5 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: sp 0xf46fecc4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: lr 0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: pc 0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: cpsr 0xa0800010 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fault 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ----- Oct 26 11:13:33 minidsp-shd go-librespot[3287]: SIGQUIT: quit Oct 26 11:13:33 minidsp-shd go-librespot[3287]: PC=0x91f8c m=8 sigcode=0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: goroutine 0 gp=0x2563208 m=8 mp=0x2049b88 [idle]: Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futex(0x2049c50, 0x80, 0x0, 0x0, 0x0, 0x0) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf3cfebf8 sp=0xf3cfebf8 pc=0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.futexsleep(0x2049c50, 0x0, 0xffffffffffffffff) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf3cfec20 sp=0xf3cfebf8 pc=0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.notesleep(0x2049c50) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf3cfec40 sp=0xf3cfec20 pc=0x22720 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.mPark(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:1761 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.stopm() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf3cfec54 sp=0xf3cfec40 pc=0x5ef04 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.findRunnable() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf3cfed00 sp=0xf3cfec54 pc=0x60ba4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.schedule() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf3cfed1c sp=0xf3cfed00 pc=0x620f4 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.goschedImpl(0x25627e8, 0x1) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:4065 +0x198 fp=0xf3cfed34 sp=0xf3cfed1c pc=0x62b1c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.gopreempt_m(...) Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/proc.go:4082 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.newstack() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/stack.go:1070 +0x3b0 fp=0xf3cfee0c sp=0xf3cfed34 pc=0x75384 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: runtime.morestack() Oct 26 11:13:33 minidsp-shd go-librespot[3287]: /usr/local/go/src/runtime/asm_arm.s:383 +0x60 fp=0xf3cfee10 sp=0xf3cfee0c pc=0x8f384 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: trap 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: error 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: oldmask 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r0 0x2049c50 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r1 0x80 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r2 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r3 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r4 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r5 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r6 0x0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r7 0xf0 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r8 0x1 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r9 0xcdca20 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: r10 0x2563208 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fp 0x6 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: ip 0x174bd380 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: sp 0xf3cfebf8 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: lr 0x52a58 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: pc 0x91f8c Oct 26 11:13:33 minidsp-shd go-librespot[3287]: cpsr 0xa08d0010 Oct 26 11:13:33 minidsp-shd go-librespot[3287]: fault 0x0 Oct 26 11:13:34 minidsp-shd go-librespot[3287]: Aborted Oct 26 11:13:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Oct 26 11:13:34 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 11:13:34 minidsp-shd volumio[626]: info: Connection to go-librespot Websocket closed Oct 26 11:13:37 minidsp-shd volumio[626]: info: Initializing connection to go-librespot Websocket Oct 26 11:13:37 minidsp-shd volumio[626]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 11:13:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 26 11:13:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Oct 26 11:13:37 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Oct 26 11:13:37 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 26 11:13:37 minidsp-shd go-librespot[3420]: go-librespot daemon starting... Oct 26 11:13:37 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:37-07:00" level=info msg="running go-librespot 0.4.0" Oct 26 11:13:37 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:37-07:00" level=debug msg="app state loaded" Oct 26 11:13:37 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:37-07:00" level=debug msg="stored credentials not found" Oct 26 11:13:37 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:37-07:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 11:13:37 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:37-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 26 11:13:37 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:37-07:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 26 11:13:37 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:37-07:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 26 11:13:37 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:37-07:00" level=info msg="zeroconf server listening on port 36281" Oct 26 11:13:40 minidsp-shd volumio[626]: info: Initializing connection to go-librespot Websocket Oct 26 11:13:40 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:40-07:00" level=debug msg="new websocket client" Oct 26 11:13:40 minidsp-shd volumio[626]: info: Connection to go-librespot Websocket established Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="obtained new client token: AADcj0yJ3IaPn/eJFA/daT96Sussx33aOFVhWXnn1ucH4SpkQruOVaI0Kw+eZ+yEoSYI8oPdikrQfYHKvvLxT7Zl1szhhiyPKBrw0nwmPLLYB4Vlzc6a9xaAEduVh9UQga3c6cHbpU2OfRi7f7BRnXmPVfbDAZXKTDF113zUF2ZOBtIh+4SpgPR7paNb5qv5HFZeZNG6qHZPO7M2ZokS1lAknHsxu69pk+gXJZeZ8q56qn99Qk5kNFWCxA==" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="completed keyexchange" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="completed challenge" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=info msg="authenticated AP" username="31************************ne" Oct 26 11:13:43 minidsp-shd volumio[626]: info: Getting Spotify volume Oct 26 11:13:43 minidsp-shd volumio[626]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioGetState Oct 26 11:13:43 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=info msg="authenticated Login5" username="31************************ne" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=info msg="accepted zeroconf from MacBook Pro" username="31************************ne" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="dealer connection opened" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=trace msg="starting accesspoint recv loop" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=trace msg="starting dealer recv loop" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=trace msg="received accesspoint ping" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="received connection id: YTkzNDc4ZTUtNDY3...MEQ2RkY3QzVEMA==" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=trace msg="received accesspoint pong ack" Oct 26 11:13:43 minidsp-shd volumio[626]: info: Oct 26 11:13:43 minidsp-shd volumio[626]: ---------------------------- MPD announces system playlist update Oct 26 11:13:43 minidsp-shd volumio[626]: info: Ignoring MPD Status Update Oct 26 11:13:43 minidsp-shd volumio[626]: info: Oct 26 11:13:43 minidsp-shd volumio[626]: ---------------------------- MPD announces state update: player Oct 26 11:13:43 minidsp-shd volumio[626]: info: ControllerMpd::getState Oct 26 11:13:43 minidsp-shd volumio[626]: verbose: ControllerMpd::sendMpdCommand status Oct 26 11:13:43 minidsp-shd volumio[626]: info: ------------------------------ 9ms Oct 26 11:13:43 minidsp-shd volumio[626]: info: sendMpdCommand status took 4 milliseconds Oct 26 11:13:43 minidsp-shd volumio[626]: verbose: ControllerMpd::parseState Oct 26 11:13:43 minidsp-shd volumio[626]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 11:13:43 minidsp-shd volumio[626]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 11:13:43 minidsp-shd volumio[626]: verbose: ControllerMpd::parseTrackInfo Oct 26 11:13:43 minidsp-shd volumio[626]: info: ControllerMpd::pushState Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreCommandRouter::servicePushState Oct 26 11:13:43 minidsp-shd volumio[626]: info: CorePlayQueue::getTrack 47 Oct 26 11:13:43 minidsp-shd volumio[626]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50845,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rosanne Cash - I'm Only Sleeping","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/mellow-flacm","trackType":""} Oct 26 11:13:43 minidsp-shd volumio[626]: verbose: CURRENT POSITION 47 Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreStateMachine::syncState stateService play Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreStateMachine::syncState currentStatus play Oct 26 11:13:43 minidsp-shd volumio[626]: info: Received an update from plugin. extracting info from payload Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:43 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:43 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:43 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:43 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:43 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:43 minidsp-shd volumio[626]: info: ------------------------------ 75ms Oct 26 11:13:43 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Oct 26 11:13:43 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="put connect state because NEW_DEVICE" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="update volume requested to 64224/65535" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="handling transfer player command from d6777b38d2164bd7569614d8357b293f6a79f002" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="resolved context of track" uri="spotify:user:31s7nhsj2g7soismsjwadnqumvne:collection" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=trace msg="fetched new page 0 with 10 items (list: 10)" uri="spotify:user:31s7nhsj2g7soismsjwadnqumvne:collection" Oct 26 11:13:43 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:43-07:00" level=debug msg="loading track (paused: false, position: 86460ms)" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=trace msg="emitting websocket event: will_play" Oct 26 11:13:44 minidsp-shd volumio[626]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:31s7nhsj2g7soismsjwadnqumvne:collection","uri":"spotify:track:7n3WO6ESKS1uCI9fgkGs66","play_origin":"your_library"}} Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 290" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="selected format OGG_VORBIS_320 (f457d4d982e561c7ff173b4993e3e6e72e300dcd)" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="requested aes key for file f457d4d982e561c7ff173b4993e3e6e72e300dcd, gid: 7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="fetched first chunk of 19, total size is 9631308 bytes" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="fetched chunk 8/18, size: 524288" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="fetched chunk 10/18, size: 524288" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="fetched chunk 9/18, size: 524288" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="fetched chunk 7/18, size: 524288" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=trace msg="seek to 86460ms (diff: 95ms, samples: 3812886, bytes: 3695946)" uri="spotify:track:7n3WO6ESKS1uCI9fgkGs66" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="created new output device" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed setting stream for spotify:track:7n3WO6ESKS1uCI9fgkGs66: ALSA error at snd_pcm_open: Device or resource busy" Oct 26 11:13:44 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:44-07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 26 11:13:45 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:45-07:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 26 11:13:45 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:45-07:00" level=trace msg="emitting websocket event: volume" Oct 26 11:13:45 minidsp-shd volumio[626]: SPOTIFY: received: {"type":"volume","data":{"value":98,"max":100}} Oct 26 11:13:45 minidsp-shd volumio[626]: SPOTIFY: RECEIVED SPOTIFY VOLUME 98 Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=debug msg="handling play player command from d6777b38d2164bd7569614d8357b293f6a79f002" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=debug msg="resolved context of track" uri="spotify:user:31s7nhsj2g7soismsjwadnqumvne:collection" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=trace msg="fetched new page 0 with 10 items (list: 10)" uri="spotify:user:31s7nhsj2g7soismsjwadnqumvne:collection" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=trace msg="emitting websocket event: will_play" Oct 26 11:13:52 minidsp-shd volumio[626]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:31s7nhsj2g7soismsjwadnqumvne:collection","uri":"spotify:track:59WN2psjkt1tyaxjspN8fp","play_origin":"your_library"}} Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=debug msg="selected format OGG_VORBIS_320 (0a74af19ff2841c1aba0aecac369f4a447f0cedf)" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=debug msg="requested aes key for file 0a74af19ff2841c1aba0aecac369f4a447f0cedf, gid: 59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=debug msg="fetched first chunk of 27, total size is 13905644 bytes" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:52 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:52-07:00" level=info msg="loaded track \"Killing In The Name\" (paused: false, position: 1ms, duration: 313573ms, prefetched: false)" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=debug msg="fetched chunk 2/26, size: 524288" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=debug msg="fetched chunk 1/26, size: 524288" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=debug msg="fetched chunk 3/26, size: 524288" uri="spotify:track:59WN2psjkt1tyaxjspN8fp" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=trace msg="scheduling prefetch in 284s" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=trace msg="emitting websocket event: metadata" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=debug msg="sending successful reply for dealer request" Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:59WN2psjkt1tyaxjspN8fp","name":"Killing In The Name","artist_names":["Rage Against The Machine"],"album_name":"Rage Against The Machine - XX (20th Anniversary Special Edition)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0254ab617bc2d4974ab6ffbece","position":1,"duration":313573,"release_date":"year:1992","track_number":2,"disc_number":1}} Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 26 11:13:53 minidsp-shd go-librespot[3420]: time="2025-10-26T11:13:53-07:00" level=trace msg="emitting websocket event: playing" Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:31s7nhsj2g7soismsjwadnqumvne:collection","uri":"spotify:track:59WN2psjkt1tyaxjspN8fp","resume":false,"play_origin":"your_library"}} Oct 26 11:13:53 minidsp-shd volumio[626]: info: Spotify is playing in volatile mode Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: UNSET VOLATILE Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: {"status":"play","position":47,"title":"Rosanne Cash - I'm Only Sleeping","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"albumart":"https://radio-directory.firebaseapp.com/minidspshd/src/images/radio-thumbnails/Radio Paradise Mellow (FLAC+).jpg","uri":"http://stream.radioparadise.com/mellow-flacm","trackType":"webradio","seek":49866,"duration":0,"samplerate":"","bitdepth":"","channels":2,"bitrate":null,"random":null,"repeat":null,"repeatSingle":false,"consume":true,"volume":98,"dbVolume":-2.5,"mute":false,"disableVolumeControl":false,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"} Oct 26 11:13:53 minidsp-shd volumio[626]: info: Setting Spotify stop after unset volatile call Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: PUSH STATE SPOTIFY Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: {"status":"play","service":"spop","title":"Killing In The Name","artist":"Rage Against The Machine","album":"Rage Against The Machine - XX (20th Anniversary Special Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e0254ab617bc2d4974ab6ffbece","uri":"spotify:track:59WN2psjkt1tyaxjspN8fp","trackType":"spotify","seek":1,"duration":313,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreCommandRouter::servicePushState Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:53 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:53 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: PUSH STATE SPOTIFY Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: {"status":"play","service":"spop","title":"Killing In The Name","artist":"Rage Against The Machine","album":"Rage Against The Machine - XX (20th Anniversary Special Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e0254ab617bc2d4974ab6ffbece","uri":"spotify:track:59WN2psjkt1tyaxjspN8fp","trackType":"spotify","seek":1001,"duration":313,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreCommandRouter::servicePushState Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreStateMachine::pushState Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 11:13:53 minidsp-shd volumio[626]: info: CoreCommandRouter::volumioPushState Oct 26 11:13:53 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output update for this device Oct 26 11:13:53 minidsp-shd volumio[626]: info: MRS: Pushing multiroomSync output Oct 26 11:13:53 minidsp-shd volumio[626]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Oct 26 11:13:54 minidsp-shd volumio[626]: info: Spotify Stop Oct 26 11:13:54 minidsp-shd volumio[626]: SPOTIFY: SPOTIFY STOP Oct 26 11:13:54 minidsp-shd volumio[626]: SPOTIFY: {"status":"play","title":"Killing In The Name","artist":"Rage Against The Machine","album":"Rage Against The Machine - XX (20th Anniversary Special Edition)","albumart":"https://i.scdn.co/image/ab67616d00001e0254ab617bc2d4974ab6ffbece","uri":"spotify:track:59WN2psjkt1tyaxjspN8fp","trackType":"spotify","codec":"ogg","seek":1001,"duration":313,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":98,"dbVolume":-2.5,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Oct 26 11:14:12 minidsp-shd volumio[626]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 26 11:14:12 minidsp-shd volumio[626]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 26 11:14:12 minidsp-shd volumio[626]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 11:14:12 minidsp-shd volumio[626]: TypeError: Cannot read property 'then' of undefined Oct 26 11:14:12 minidsp-shd volumio[626]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 26 11:14:12 minidsp-shd volumio[626]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2249:30) Oct 26 11:14:12 minidsp-shd volumio[626]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1411:26) Oct 26 11:14:12 minidsp-shd volumio[626]: at Socket.emit (events.js:400:28) Oct 26 11:14:12 minidsp-shd volumio[626]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 26 11:14:12 minidsp-shd volumio[626]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 26 11:14:12 minidsp-shd volumio[626]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 11:14:13 minidsp-shd go-librespot[3420]: time="2025-10-26T11:14:13-07:00" level=trace msg="sent dealer ping" Oct 26 11:14:13 minidsp-shd go-librespot[3420]: time="2025-10-26T11:14:13-07:00" level=trace msg="received dealer pong" Oct 26 11:14:13 minidsp-shd sudo[3442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-26 11:13 Oct 26 11:14:13 minidsp-shd sudo[3442]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"