-- Logs begin at Thu 2025-03-13 16:25:56 CET, end at Thu 2025-03-13 17:00:12 CET. -- Mar 13 16:59:03 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:59:03 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:59:03 rivoplus volumio[3189]: info: Discovery: Getting this device information Mar 13 16:59:03 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 16:59:03 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:59:06 rivoplus volumio[3189]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 13 16:59:06 rivoplus volumio[3189]: info: Preparing to save Alsa Options, stopping services first Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreCommandRouter::volumioPause Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreStateMachine::pause Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreStateMachine::stPlaybackTimer Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreStateMachine::servicePause Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreCommandRouter::servicePause Mar 13 16:59:06 rivoplus volumio[3189]: info: Spotify Received pause Mar 13 16:59:06 rivoplus volumio[3189]: SPOTIFY: SPOTIFY PAUSE Mar 13 16:59:06 rivoplus volumio[3189]: SPOTIFY: {"status":"play","title":"Mata Hari","artist":"Efendi","album":"Mata Hari","albumart":"https://i.scdn.co/image/ab67616d00001e02292905809c766a768e4d0113","uri":"spotify:track:3wuvVwA5RaifHz1uqKac8P","trackType":"spotify","codec":"ogg","seek":0,"duration":160,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":25,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Mar 13 16:59:06 rivoplus volumio[3189]: info: Sending Spotify command to local API: /player/pause Mar 13 16:59:06 rivoplus volumio[3189]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"}} Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 16:59:06 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:06 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:06 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:06 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:06 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 16:59:06 rivoplus volumio[3189]: info: Found match in Cards Database for ignoring default Mixer Mar 13 16:59:06 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:06 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:06 rivoplus volumio[3189]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Mar 13 16:59:06 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 13 16:59:06 rivoplus vtcs[4047]: [2025-03-13 16:59:06.847] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 13 16:59:06 rivoplus vtcs[4047]: [2025-03-13 16:59:06.851] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Mar 13 16:59:06 rivoplus vtcs[4047]: [2025-03-13 16:59:06.851] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 13 16:59:06 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 13 16:59:06 rivoplus volumio[3189]: info: Ignoring Mixers Options Mar 13 16:59:06 rivoplus volumio[3189]: info: Device 5 does not have any Mixer Control Available Mar 13 16:59:06 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 13 16:59:06 rivoplus sudo[11890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:06 rivoplus sudo[11890]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:06 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 13 16:59:06 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Mar 13 16:59:06 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:06 rivoplus systemd[1]: vtcs.service: Succeeded. Mar 13 16:59:06 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Mar 13 16:59:06 rivoplus sudo[11890]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:06 rivoplus sudo[11893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:06 rivoplus sudo[11893]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:07 rivoplus sudo[11893]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:07 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:07 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 13 16:59:07 rivoplus volumio[3189]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 13 16:59:07 rivoplus volumio[3189]: info: Disabling external Volume Control Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreStateMachine::pushState Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::volumioPushState Mar 13 16:59:07 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:59:07 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output Mar 13 16:59:07 rivoplus volumio[3189]: info: Preparing to generate the ALSA configuration file Mar 13 16:59:07 rivoplus sudo[11904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:07 rivoplus sudo[11904]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:07 rivoplus sudo[11907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:07 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:07 rivoplus sudo[11907]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:07 rivoplus sudo[11904]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:07 rivoplus volumio[3189]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 13 16:59:07 rivoplus volumio[3189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 13 16:59:07 rivoplus volumio[3189]: info: Reading ALSA contributions from plugins. Mar 13 16:59:07 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:07 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:07 rivoplus sudo[11907]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:07 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:07 rivoplus go-librespot[9036]: time="2025-03-13T16:59:07+01:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Mar 13 16:59:07 rivoplus go-librespot[9036]: time="2025-03-13T16:59:07+01:00" level=debug msg="pause track at 0ms" Mar 13 16:59:07 rivoplus go-librespot[9036]: SIGSEGV: segmentation violation Mar 13 16:59:07 rivoplus go-librespot[9036]: PC=0xf71d58b8 m=0 sigcode=1 addr=0x10 Mar 13 16:59:07 rivoplus go-librespot[9036]: signal arrived during cgo execution Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 29 gp=0x2da5208 m=0 mp=0xbc6020 [syscall]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.cgocall(0x5fbf58, 0x3155ecc) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/cgocall.go:157 +0x50 fp=0x3155eb4 sp=0x3155e9c pc=0x1a968 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/output._Cfunc_snd_pcm_close(0xf3318348) Mar 13 16:59:07 rivoplus go-librespot[9036]: _cgo_gotypes.go:415 +0x38 fp=0x3155ec8 sp=0x3155eb4 pc=0x498d7c Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/output.(*alsaOutput).Pause.func1(0x303fc00) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/output/driver-alsa.go:342 +0x50 fp=0x3155ee4 sp=0x3155ec8 pc=0x49cb00 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/output.(*alsaOutput).Pause(0x303fc00) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/output/driver-alsa.go:342 +0xc0 fp=0x3155f14 sp=0x3155ee4 pc=0x49ca08 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x3104100) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/player/player.go:244 +0x94c fp=0x3155fe4 sp=0x3155f14 pc=0x4c9568 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/player/player.go:163 +0x28 fp=0x3155fec sp=0x3155fe4 pc=0x4c89f8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3155fec sp=0x3155fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/player/player.go:163 +0x1c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 1 gp=0x2c02128 m=nil [select, 15 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x731100, 0x0, 0x9, 0x3, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c5ad2c sp=0x2c5ad18 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.selectgo(0x2c5ae2c, 0x2d55df8, 0x0, 0x0, 0x2, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2c5adcc sp=0x2c5ad2c pc=0x6dfc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x2e34000, 0x2c5aeb8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/zeroconf/zeroconf.go:270 +0x228 fp=0x2c5ae70 sp=0x2c5adcc pc=0x55af74 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*App).withAppPlayer(0x2d80980, {0x7d1e9c, 0xbefff8}, 0x2d83480) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:316 +0x514 fp=0x2c5aef8 sp=0x2c5ae70 pc=0x5f000c Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*App).Zeroconf(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:168 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.main() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:610 +0x918 fp=0x2c5afa8 sp=0x2c5aef8 pc=0x5f2f94 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.main() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x2c5afec sp=0x2c5afa8 pc=0x59c78 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c5afec sp=0x2c5afec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 2 gp=0x2c027e8 m=nil [force gc (idle), 2 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310cc, 0xbc4928, 0x11, 0xa, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c44fd4 sp=0x2c44fc0 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goparkunlock(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:408 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.forcegchelper() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x2c44fec sp=0x2c44fd4 pc=0x5a044 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c44fec sp=0x2c44fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by runtime.init.5 in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:314 +0x1c Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 3 gp=0x2c02b48 m=nil [GC sweep wait]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310cc, 0xbc4ee0, 0xc, 0x9, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c457c4 sp=0x2c457b0 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goparkunlock(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:408 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.bgsweep(0x2c6c000) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x2c457e4 sp=0x2c457c4 pc=0x42990 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gcenable.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x2c457ec sp=0x2c457e4 pc=0x32ab0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c457ec sp=0x2c457ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by runtime.gcenable in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 4 gp=0x2c02c68 m=nil [GC scavenge wait]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310cc, 0xbc5508, 0xd, 0xa, 0x2) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c45fb4 sp=0x2c45fa0 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goparkunlock(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:408 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.(*scavengerState).park(0xbc5508) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2c45fc8 sp=0x2c45fb4 pc=0x3fcb0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.bgscavenge(0x2c6c000) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2c45fe4 sp=0x2c45fc8 pc=0x403e8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gcenable.gowrap2() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x2c45fec sp=0x2c45fe4 pc=0x32a5c Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c45fec sp=0x2c45fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by runtime.gcenable in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 18 gp=0x2c8e248 m=nil [finalizer wait]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f78, 0xbf0094, 0x10, 0xa, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c4078c sp=0x2c40778 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.runfinq() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x2c407ec sp=0x2c4078c pc=0x3189c Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c407ec sp=0x2c407ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by runtime.createfing in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 36 gp=0x2da4fc8 m=nil [IO wait]: Mar 13 16:59:07 rivoplus volumio[3189]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Mar 13 16:59:07 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:07 rivoplus volumio[3189]: amixer: Unable to find simple control 'AVM Class 2 Audio ',0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310bc, 0xf4ac3f08, 0x2, 0x2, 0x5) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2decd28 sp=0x2decd14 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.netpollblock(0xf4ac3ef8, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2decd40 sp=0x2decd28 pc=0x51a14 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.runtime_pollWait(0xf4ac3ef8, 0x72) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2decd54 sp=0x2decd40 pc=0x8c5c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).wait(0x2db4dd8, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2decd68 sp=0x2decd54 pc=0x107518 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).waitRead(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*FD).Accept(0x2db4dc0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2decdb0 sp=0x2decd68 pc=0x10bd84 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*netFD).accept(0x2db4dc0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2dece18 sp=0x2decdb0 pc=0x17b56c Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*TCPListener).accept(0x2d8a5a0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2dece30 sp=0x2dece18 pc=0x19366c Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*TCPListener).Accept(0x2d8a5a0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x2dece4c sp=0x2dece30 pc=0x1926d8 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*onceCloseListener).Accept(0x2c6e360) Mar 13 16:59:07 rivoplus go-librespot[9036]: :1 +0x34 fp=0x2dece64 sp=0x2dece4c pc=0x35f8c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*Server).Serve(0x2c7e0a8, {0x7d1680, 0x2d8a5a0}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x2decefc sp=0x2dece64 pc=0x33df7c Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.Serve(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2794 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*ConcreteApiServer).serve(0x2d8e9c0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/api_server.go:610 +0x974 fp=0x2decfe4 sp=0x2decefc pc=0x5e5e00 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.NewApiServer.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/api_server.go:289 +0x28 fp=0x2decfec sp=0x2decfe4 pc=0x5e4cd0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2decfec sp=0x2decfec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by main.NewApiServer in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/api_server.go:289 +0x310 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 50 gp=0x2da50e8 m=nil [runnable]: Mar 13 16:59:07 rivoplus go-librespot[9036]: syscall.Syscall6(0x127, 0xa, 0x1, 0x26, 0x2df2d68, 0x2df2d64, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/syscall/syscall_linux.go:91 +0x8 fp=0x2df2d10 sp=0x2df2cd8 pc=0xa56e8 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/sys/unix.getsockopt(0xa, 0x1, 0x26, 0x2df2d68, 0x2df2d64) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/sys@v0.22.0/unix/zsyscall_linux_arm.go:101 +0x5c fp=0x2df2d44 sp=0x2df2d10 pc=0x4d3014 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/sys/unix.GetsockoptInt(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/sys@v0.22.0/unix/syscall_unix.go:279 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/sys/unix.init.func1(0xa) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/sys@v0.22.0/unix/syscall_linux.go:972 +0x4c fp=0x2df2d6c sp=0x2df2d44 pc=0x4d0350 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/sys/unix.anyToSockaddr(0xa, 0x2c168c0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/sys@v0.22.0/unix/syscall_linux.go:1022 +0x750 fp=0x2df2db8 sp=0x2df2d6c pc=0x4d1970 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/sys/unix.RecvmsgBuffers(0xa, {0x310db20, 0x1, 0x1}, {0x2d9fda0, 0x18, 0x18}, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/sys@v0.22.0/unix/syscall_unix.go:394 +0x178 fp=0x2df2e08 sp=0x2df2db8 pc=0x4d2668 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/net/internal/socket.recvmsg(0xa, {0x310db20, 0x1, 0x1}, {0x2d9fda0, 0x18, 0x18}, 0x0, {0x7042dc, 0x3}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/sys_unix.go:34 +0x54 fp=0x2df2e5c sp=0x2df2e08 pc=0x4fcea0 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/net/internal/socket.(*Conn).recvMsg.func1(0xa) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:24 +0x98 fp=0x2df2ec4 sp=0x2df2e5c pc=0x4fbb40 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*FD).RawRead(0x2eca050, 0x3144ab0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_unix.go:705 +0x138 fp=0x2df2ef0 sp=0x2df2ec4 pc=0x10c95c Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*rawConn).Read(0x2ec2090, 0x3144ab0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x2df2f10 sp=0x2df2ef0 pc=0x18da94 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2ed8170, 0x3144a80, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2df2f40 sp=0x2df2f10 pc=0x4fb96c Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2ece038, {0x30aa000, 0x10000, 0x10000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x2df2f9c sp=0x2df2f40 pc=0x4ff954 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/grandcat/zeroconf.(*Server).recv4(0x2c70780, 0x2ece030) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x2df2fe0 sp=0x2df2f9c pc=0x5561e4 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x2df2fec sp=0x2df2fe0 pc=0x555e9c Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2df2fec sp=0x2df2fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 42 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 6 gp=0x2da5328 m=nil [GC worker (idle), 2 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f88, 0x2cba348, 0x1a, 0xa, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2dec790 sp=0x2dec77c pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gcBgMarkWorker() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2dec7ec sp=0x2dec790 pc=0x356d8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2dec7ec sp=0x2dec7ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 19 gp=0x2c8ea28 m=nil [GC worker (idle)]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f88, 0x2cba360, 0x1a, 0xa, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c44790 sp=0x2c4477c pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gcBgMarkWorker() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2c447ec sp=0x2c44790 pc=0x356d8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c447ec sp=0x2c447ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 20 gp=0x2c8eb48 m=nil [GC worker (idle), 2 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f88, 0x2cba378, 0x1a, 0xa, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c40f90 sp=0x2c40f7c pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gcBgMarkWorker() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2c40fec sp=0x2c40f90 pc=0x356d8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c40fec sp=0x2c40fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 21 gp=0x2c8ec68 m=nil [GC worker (idle), 2 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f88, 0x2d9f260, 0x1a, 0xa, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c41790 sp=0x2c4177c pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gcBgMarkWorker() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2c417ec sp=0x2c41790 pc=0x356d8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c417ec sp=0x2c417ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 51 gp=0x2f00008 m=nil [IO wait, 16 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310bc, 0xf4ac3c88, 0x2, 0x2, 0x5) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2de865c sp=0x2de8648 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.netpollblock(0xf4ac3c78, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2de8674 sp=0x2de865c pc=0x51a14 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.runtime_pollWait(0xf4ac3c78, 0x72) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2de8688 sp=0x2de8674 pc=0x8c5c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).wait(0x2eca0b8, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2de869c sp=0x2de8688 pc=0x107518 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).waitRead(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*FD).RawRead(0x2eca0a0, 0x2f02030) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2de86c8 sp=0x2de869c pc=0x10c988 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*rawConn).Read(0x2ec20b8, 0x2f02030) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x2de86e8 sp=0x2de86c8 pc=0x18da94 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2ed8180, 0x2f02000, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2de8718 sp=0x2de86e8 pc=0x4fb96c Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Mar 13 16:59:07 rivoplus go-librespot[9036]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2ece068, {0x2f24000, 0x10000, 0x10000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x2de879c sp=0x2de8718 pc=0x5032dc Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/grandcat/zeroconf.(*Server).recv6(0x2c70780, 0x2ece060) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x2de87e0 sp=0x2de879c pc=0x5563d8 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x2de87ec sp=0x2de87e0 pc=0x555e40 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2de87ec sp=0x2de87ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 42 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 115 gp=0x2eee008 m=nil [select, 3 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x731100, 0x0, 0x9, 0x3, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3157cb0 sp=0x3157c9c pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.selectgo(0x3157e14, 0x3157d80, 0x0, 0x0, 0x3, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x3157d50 sp=0x3157cb0 pc=0x6dfc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).recvLoop(0x311c140) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/audio/provider.go:63 +0x118 fp=0x3157fe4 sp=0x3157d50 pc=0x489a3c Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/audio/provider.go:53 +0x28 fp=0x3157fec sp=0x3157fe4 pc=0x48a764 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3157fec sp=0x3157fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 30 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/audio/provider.go:53 +0x60 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 44 gp=0x2eee128 m=nil [chan receive]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f5c, 0x2daa1b0, 0xe, 0x7, 0x2) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2dedf48 sp=0x2dedf34 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.chanrecv(0x2daa180, 0x2dedfd0, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2dedf84 sp=0x2dedf48 pc=0x1d1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.chanrecv2(0x2daa180, 0x2dedfd0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x2dedf98 sp=0x2dedf84 pc=0x1cd04 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*App).withAppPlayer.func1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:264 +0x68 fp=0x2dedfec sp=0x2dedf98 pc=0x5f0ba4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2dedfec sp=0x2dedfec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by main.(*App).withAppPlayer in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:261 +0x424 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 45 gp=0x2eee248 m=nil [chan receive, 16 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f5c, 0x2daa130, 0xe, 0x7, 0x2) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2dee738 sp=0x2dee724 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.chanrecv(0x2daa100, 0x2dee7e0, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2dee774 sp=0x2dee738 pc=0x1d1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.chanrecv2(0x2daa100, 0x2dee7e0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x2dee788 sp=0x2dee774 pc=0x1cd04 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*App).withAppPlayer.func2() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:280 +0x6c fp=0x2dee7ec sp=0x2dee788 pc=0x5f076c Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2dee7ec sp=0x2dee7ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by main.(*App).withAppPlayer in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:277 +0x4c4 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 46 gp=0x2eee368 m=nil [IO wait, 3 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310bc, 0xf4ac3dc8, 0x2, 0x2, 0x5) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2deede8 sp=0x2deedd4 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.netpollblock(0xf4ac3db8, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2deee00 sp=0x2deede8 pc=0x51a14 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.runtime_pollWait(0xf4ac3db8, 0x72) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2deee14 sp=0x2deee00 pc=0x8c5c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).wait(0x2eca018, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2deee28 sp=0x2deee14 pc=0x107518 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).waitRead(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*FD).Accept(0x2eca000) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2deee70 sp=0x2deee28 pc=0x10bd84 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*netFD).accept(0x2eca000) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2deeed8 sp=0x2deee70 pc=0x17b56c Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*TCPListener).accept(0x2eba048) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2deeef0 sp=0x2deeed8 pc=0x19366c Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*TCPListener).Accept(0x2eba048) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x2deef0c sp=0x2deeef0 pc=0x1926d8 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*onceCloseListener).Accept(0x2c6ea60) Mar 13 16:59:07 rivoplus go-librespot[9036]: :1 +0x34 fp=0x2deef24 sp=0x2deef0c pc=0x35f8c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*Server).Serve(0x2da2008, {0x7d1680, 0x2eba048}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x2deefbc sp=0x2deef24 pc=0x33df7c Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.Serve(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2794 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/zeroconf/zeroconf.go:267 +0x8c fp=0x2deefec sp=0x2deefbc pc=0x55b070 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2deefec sp=0x2deefec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/zeroconf/zeroconf.go:267 +0x160 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 47 gp=0x2eee6c8 m=nil [IO wait, 16 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310bc, 0xf4ac3be8, 0x2, 0x2, 0x5) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2df687c sp=0x2df6868 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.netpollblock(0xf4ac3bd8, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2df6894 sp=0x2df687c pc=0x51a14 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.runtime_pollWait(0xf4ac3bd8, 0x72) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2df68a8 sp=0x2df6894 pc=0x8c5c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).wait(0x2eca108, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2df68bc sp=0x2df68a8 pc=0x107518 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).waitRead(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*FD).Read(0x2eca0f0, {0x2edd000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2df6904 sp=0x2df68bc pc=0x108704 Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*netFD).Read(0x2eca0f0, {0x2edd000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2df6930 sp=0x2df6904 pc=0x179678 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*conn).Read(0x2ec2478, {0x2edd000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2df695c sp=0x2df6930 pc=0x189858 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*TCPConn).Read(0x2ec2478, {0x2edd000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: :1 +0x44 fp=0x2df697c sp=0x2df695c pc=0x19caac Mar 13 16:59:07 rivoplus go-librespot[9036]: io.(*multiReader).Read(0x2ed88c0, {0x2edd000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x2df69a8 sp=0x2df697c pc=0x102728 Mar 13 16:59:07 rivoplus go-librespot[9036]: bufio.(*Reader).fill(0x2ecf170) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x2df69cc sp=0x2df69a8 pc=0x2bab20 Mar 13 16:59:07 rivoplus go-librespot[9036]: bufio.(*Reader).ReadByte(0x2ecf170) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x2df69d8 sp=0x2df69cc pc=0x2bb3a4 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.readFrameHeader(0x2ecf170, {0x2d42178, 0x8, 0x8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2df6a18 sp=0x2df69d8 pc=0x46db90 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2d42148, {0x7d1e7c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2df6ad8 sp=0x2df6a18 pc=0x4707f4 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).readLoop(0x2d42148, {0x7d1e7c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2df6b9c sp=0x2df6ad8 pc=0x4702dc Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).reader(0x2d42148, {0x7d1e7c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2df6c28 sp=0x2df6b9c pc=0x4714f0 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).Reader(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).Read(0x2d42148, {0x7d1e7c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2df6c50 sp=0x2df6c28 pc=0x46fbc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*ConcreteApiServer).serve.func17({0x7d17b4, 0x2da23c8}, 0x2da20a8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/api_server.go:580 +0x3c0 fp=0x2df6ccc sp=0x2df6c50 pc=0x5e66d0 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.HandlerFunc.ServeHTTP(0x2c301a0, {0x7d17b4, 0x2da23c8}, 0x2da20a8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2df6cdc sp=0x2df6ccc pc=0x33a560 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*ServeMux).ServeHTTP(0x2c7a000, {0x7d17b4, 0x2da23c8}, 0x2da20a8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2df6d18 sp=0x2df6cdc pc=0x33c3c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d17b4, 0x2da23c8}, 0x2da20a8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2df6d40 sp=0x2df6d18 pc=0x5e60d0 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.HandlerFunc.ServeHTTP(0x2c0e190, {0x7d17b4, 0x2da23c8}, 0x2da20a8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2df6d50 sp=0x2df6d40 pc=0x33a560 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.serverHandler.ServeHTTP({0x2c7e0a8}, {0x7d17b4, 0x2da23c8}, 0x2da20a8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2df6d6c sp=0x2df6d50 pc=0x33db50 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*conn).serve(0x2f86120, {0x7d1ebc, 0x2c26198}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2df6fdc sp=0x2df6d6c pc=0x338da0 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*Server).Serve.gowrap3() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2df6fec sp=0x2df6fdc pc=0x33e3f4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2df6fec sp=0x2df6fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by net/http.(*Server).Serve in goroutine 36 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:3285 +0x468 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 49 gp=0x2eee7e8 m=nil [select, 2 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x731100, 0x0, 0x9, 0x3, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2ded6a8 sp=0x2ded694 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.selectgo(0x2ded7a4, 0x2ded76c, 0x0, 0x0, 0x5, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2ded748 sp=0x2ded6a8 pc=0x6dfc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2d42148) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2ded7e4 sp=0x2ded748 pc=0x46aa3c Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.newConn.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2ded7ec sp=0x2ded7e4 pc=0x46a630 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2ded7ec sp=0x2ded7ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by nhooyr.io/websocket.newConn in goroutine 47 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 30 gp=0x31405a8 m=nil [chan receive]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f5c, 0x30870b0, 0xe, 0x7, 0x2) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2fed6b8 sp=0x2fed6a4 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.chanrecv(0x3087080, 0x2fed780, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2fed6f4 sp=0x2fed6b8 pc=0x1d1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.chanrecv1(0x3087080, 0x2fed780) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x2fed708 sp=0x2fed6f4 pc=0x1cce0 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/player.(*Player).Pause(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/player/player.go:362 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*AppPlayer).pause(0x2f3c460, {0x7d1e9c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/controls.go:392 +0x1ec fp=0x2fed798 sp=0x2fed708 pc=0x5ec0d0 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*AppPlayer).handleApiRequest(0x2f3c460, {0x7d1e9c, 0xbefff8}, {{0x704a97, 0x5}, {0x0, 0x0}, 0x3086f40}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/player.go:429 +0x940 fp=0x2fed8f4 sp=0x2fed798 pc=0x5f6ba0 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*AppPlayer).Run(0x2f3c460, {0x7d1e9c, 0xbefff8}, 0x312c3c0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/player.go:590 +0x6bc fp=0x2fedfd8 sp=0x2fed8f4 pc=0x5f84ac Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*App).withAppPlayer.func3.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:352 +0x40 fp=0x2fedfec sp=0x2fedfd8 pc=0x5f06d4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2fedfec sp=0x2fedfec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by main.(*App).withAppPlayer.func3 in goroutine 1 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/main.go:352 +0x50c Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 78 gp=0x2da4a28 m=nil [IO wait, 2 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310bc, 0xf4ac3a08, 0x2, 0x2, 0x5) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c57d14 sp=0x2c57d00 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.netpollblock(0xf4ac39f8, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2c57d2c sp=0x2c57d14 pc=0x51a14 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.runtime_pollWait(0xf4ac39f8, 0x72) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2c57d40 sp=0x2c57d2c pc=0x8c5c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).wait(0x2ecafb8, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c57d54 sp=0x2c57d40 pc=0x107518 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).waitRead(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*FD).Read(0x2ecafa0, {0x2f3b700, 0x3, 0x3}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2c57d9c sp=0x2c57d54 pc=0x108704 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*netFD).Read(0x2ecafa0, {0x2f3b700, 0x3, 0x3}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2c57dc8 sp=0x2c57d9c pc=0x179678 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*conn).Read(0x2ec2040, {0x2f3b700, 0x3, 0x3}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2c57df4 sp=0x2c57dc8 pc=0x189858 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*TCPConn).Read(0x2ec2040, {0x2f3b700, 0x3, 0x3}) Mar 13 16:59:07 rivoplus go-librespot[9036]: :1 +0x44 fp=0x2c57e14 sp=0x2c57df4 pc=0x19caac Mar 13 16:59:07 rivoplus go-librespot[9036]: io.ReadAtLeast({0x7cdfa4, 0x2ec2040}, {0x2f3b700, 0x3, 0x3}, 0x3) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2c57e40 sp=0x2c57e14 pc=0x1014c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: io.ReadFull(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/io/io.go:354 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x2e1d4c0, {0x7d1e9c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/ap/shannon.go:124 +0x3a0 fp=0x2c57f04 sp=0x2c57e40 pc=0x485c50 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x2cf66e8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/ap/ap.go:276 +0x68 fp=0x2c57fe4 sp=0x2c57f04 pc=0x4823c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/ap/ap.go:260 +0x28 fp=0x2c57fec sp=0x2c57fe4 pc=0x482334 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c57fec sp=0x2c57fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 30 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/ap/ap.go:260 +0x98 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 77 gp=0x2da4b48 m=nil [select]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x731100, 0x0, 0x9, 0x3, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c43ea8 sp=0x2c43e94 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.selectgo(0x2c43fa4, 0x2c43f6c, 0x0, 0x0, 0x5, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2c43f48 sp=0x2c43ea8 pc=0x6dfc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2db2788) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2c43fe4 sp=0x2c43f48 pc=0x46aa3c Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.newConn.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2c43fec sp=0x2c43fe4 pc=0x46a630 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c43fec sp=0x2c43fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by nhooyr.io/websocket.newConn in goroutine 30 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 79 gp=0x2da4c68 m=nil [select, 2 minutes]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x731100, 0x0, 0x9, 0x3, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2def6b8 sp=0x2def6a4 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.selectgo(0x2def7d4, 0x2def77c, 0x0, 0x0, 0x2, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2def758 sp=0x2def6b8 pc=0x6dfc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x2cf66e8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/ap/ap.go:355 +0x8c fp=0x2def7e4 sp=0x2def758 pc=0x482be8 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/ap/ap.go:264 +0x28 fp=0x2def7ec sp=0x2def7e4 pc=0x4822e0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2def7ec sp=0x2def7ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 30 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/ap/ap.go:264 +0x15c Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 80 gp=0x2da4d88 m=nil [IO wait]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310bc, 0xf4ac3b48, 0x2, 0x2, 0x5) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x33c0940 sp=0x33c092c pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.netpollblock(0xf4ac3b38, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x33c0958 sp=0x33c0940 pc=0x51a14 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.runtime_pollWait(0xf4ac3b38, 0x72) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x33c096c sp=0x33c0958 pc=0x8c5c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).wait(0x3055008, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x33c0980 sp=0x33c096c pc=0x107518 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).waitRead(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*FD).Read(0x3054ff0, {0x2fc4000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x33c09c8 sp=0x33c0980 pc=0x108704 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*netFD).Read(0x3054ff0, {0x2fc4000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x33c09f4 sp=0x33c09c8 pc=0x179678 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*conn).Read(0x2c30418, {0x2fc4000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x33c0a20 sp=0x33c09f4 pc=0x189858 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*TCPConn).Read(0x2c30418, {0x2fc4000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: :1 +0x44 fp=0x33c0a40 sp=0x33c0a20 pc=0x19caac Mar 13 16:59:07 rivoplus go-librespot[9036]: crypto/tls.(*atLeastReader).Read(0x2e63140, {0x2fc4000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x33c0a6c sp=0x33c0a40 pc=0x2793ec Mar 13 16:59:07 rivoplus go-librespot[9036]: bytes.(*Buffer).ReadFrom(0x2cbf5cc, {0x7cedbc, 0x2e63140}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x33c0aa8 sp=0x33c0a6c pc=0x136dd0 Mar 13 16:59:07 rivoplus go-librespot[9036]: crypto/tls.(*Conn).readFromUntil(0x2cbf448, {0x7cdfa4, 0x2c30418}, 0x5) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x33c0ad0 sp=0x33c0aa8 pc=0x279648 Mar 13 16:59:07 rivoplus go-librespot[9036]: crypto/tls.(*Conn).readRecordOrCCS(0x2cbf448, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x33c0c34 sp=0x33c0ad0 pc=0x276d48 Mar 13 16:59:07 rivoplus go-librespot[9036]: crypto/tls.(*Conn).readRecord(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/crypto/tls/conn.go:588 Mar 13 16:59:07 rivoplus go-librespot[9036]: crypto/tls.(*Conn).Read(0x2cbf448, {0x2fc5000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x33c0c64 sp=0x33c0c34 pc=0x27cbb8 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*readWriteCloserBody).Read(0x310c290, {0x2fc5000, 0x1000, 0x1000}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/transport.go:2429 +0xec fp=0x33c0c84 sp=0x33c0c64 pc=0x35486c Mar 13 16:59:07 rivoplus go-librespot[9036]: bufio.(*Reader).fill(0x2c00420) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x33c0ca8 sp=0x33c0c84 pc=0x2bab20 Mar 13 16:59:07 rivoplus go-librespot[9036]: bufio.(*Reader).ReadByte(0x2c00420) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x33c0cb4 sp=0x33c0ca8 pc=0x2bb3a4 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.readFrameHeader(0x2c00420, {0x2db27b8, 0x8, 0x8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x33c0cf4 sp=0x33c0cb4 pc=0x46db90 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2db2788, {0x7d1e7c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x33c0db4 sp=0x33c0cf4 pc=0x4707f4 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).readLoop(0x2db2788, {0x7d1e7c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x33c0e78 sp=0x33c0db4 pc=0x4702dc Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).reader(0x2db2788, {0x7d1e7c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x33c0f04 sp=0x33c0e78 pc=0x4714f0 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).Reader(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Mar 13 16:59:07 rivoplus go-librespot[9036]: nhooyr.io/websocket.(*Conn).Read(0x2db2788, {0x7d1e7c, 0xbefff8}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x33c0f2c sp=0x33c0f04 pc=0x46fbc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x2dc82c8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/dealer/dealer.go:188 +0x58 fp=0x33c0fe4 sp=0x33c0f2c pc=0x4e5d54 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/dealer/dealer.go:125 +0x28 fp=0x33c0fec sp=0x33c0fe4 pc=0x4e7828 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x33c0fec sp=0x33c0fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 30 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/dealer/dealer.go:125 +0x98 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 81 gp=0x2da5448 m=nil [select]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x731100, 0x0, 0x9, 0x3, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3158e98 sp=0x3158e84 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.selectgo(0x3158fd4, 0x3158f60, 0x0, 0x0, 0x2, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x3158f38 sp=0x3158e98 pc=0x6dfc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x2dc82c8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/dealer/dealer.go:138 +0x8c fp=0x3158fe4 sp=0x3158f38 pc=0x4e58cc Mar 13 16:59:07 rivoplus go-librespot[9036]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/dealer/dealer.go:129 +0x28 fp=0x3158fec sp=0x3158fe4 pc=0x4e77d4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3158fec sp=0x3158fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 30 Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/dealer/dealer.go:129 +0x15c Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 247 gp=0x2eeeb48 m=nil [IO wait]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x7310bc, 0xf4ac3968, 0x2, 0x2, 0x5) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x30de6b4 sp=0x30de6a0 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.netpollblock(0xf4ac3958, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x30de6cc sp=0x30de6b4 pc=0x51a14 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.runtime_pollWait(0xf4ac3958, 0x72) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x30de6e0 sp=0x30de6cc pc=0x8c5c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).wait(0x3131008, 0x72, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x30de6f4 sp=0x30de6e0 pc=0x107518 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*pollDesc).waitRead(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 13 16:59:07 rivoplus go-librespot[9036]: internal/poll.(*FD).Read(0x3130ff0, {0x2c6f30d, 0x1, 0x1}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x30de73c sp=0x30de6f4 pc=0x108704 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*netFD).Read(0x3130ff0, {0x2c6f30d, 0x1, 0x1}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x30de768 sp=0x30de73c pc=0x179678 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*conn).Read(0x2c30c80, {0x2c6f30d, 0x1, 0x1}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x30de794 sp=0x30de768 pc=0x189858 Mar 13 16:59:07 rivoplus go-librespot[9036]: net.(*TCPConn).Read(0x2c30c80, {0x2c6f30d, 0x1, 0x1}) Mar 13 16:59:07 rivoplus go-librespot[9036]: :1 +0x44 fp=0x30de7b4 sp=0x30de794 pc=0x19caac Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*connReader).backgroundRead(0x2c6f300) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:681 +0x40 fp=0x30de7e4 sp=0x30de7b4 pc=0x332a68 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*connReader).startBackgroundRead.gowrap2() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:677 +0x28 fp=0x30de7ec sp=0x30de7e4 pc=0x3329a0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x30de7ec sp=0x30de7ec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by net/http.(*connReader).startBackgroundRead in goroutine 234 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:677 +0x160 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 234 gp=0x3102ea8 m=nil [chan receive]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gopark(0x730f5c, 0x3086f70, 0xe, 0x7, 0x2) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x3156bcc sp=0x3156bb8 pc=0x5a208 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.chanrecv(0x3086f40, 0x3156c98, 0x1) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x3156c08 sp=0x3156bcc pc=0x1d1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.chanrecv1(0x3086f40, 0x3156c98) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x3156c1c sp=0x3156c08 pc=0x1cce0 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*ConcreteApiServer).handleRequest(0x2d8e9c0, {{0x704a97, 0x5}, {0x0, 0x0}, 0x3086f40}, {0x7d17b4, 0x302a148}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/api_server.go:312 +0x74 fp=0x3156ca8 sp=0x3156c1c pc=0x5e4e80 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*ConcreteApiServer).serve.func6({0x7d17b4, 0x302a148}, 0x2fd4008) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/cmd/daemon/api_server.go:408 +0x9c fp=0x3156ccc sp=0x3156ca8 pc=0x5e7a0c Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.HandlerFunc.ServeHTTP(0x2c30098, {0x7d17b4, 0x302a148}, 0x2fd4008) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x3156cdc sp=0x3156ccc pc=0x33a560 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*ServeMux).ServeHTTP(0x2c7a000, {0x7d17b4, 0x302a148}, 0x2fd4008) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x3156d18 sp=0x3156cdc pc=0x33c3c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d17b4, 0x302a148}, 0x2fd4008) Mar 13 16:59:07 rivoplus go-librespot[9036]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x3156d40 sp=0x3156d18 pc=0x5e60d0 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.HandlerFunc.ServeHTTP(0x2c0e190, {0x7d17b4, 0x302a148}, 0x2fd4008) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x3156d50 sp=0x3156d40 pc=0x33a560 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.serverHandler.ServeHTTP({0x2c7e0a8}, {0x7d17b4, 0x302a148}, 0x2fd4008) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x3156d6c sp=0x3156d50 pc=0x33db50 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*conn).serve(0x2cd8840, {0x7d1ebc, 0x2c26198}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x3156fdc sp=0x3156d6c pc=0x338da0 Mar 13 16:59:07 rivoplus go-librespot[9036]: net/http.(*Server).Serve.gowrap3() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x3156fec sp=0x3156fdc pc=0x33e3f4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit({}) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x3156fec sp=0x3156fec pc=0x90e8c Mar 13 16:59:07 rivoplus go-librespot[9036]: created by net/http.(*Server).Serve in goroutine 36 Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/net/http/server.go:3285 +0x468 Mar 13 16:59:07 rivoplus go-librespot[9036]: trap 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: error 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: oldmask 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r0 0xf33176c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r1 0x5fbf58 Mar 13 16:59:07 rivoplus go-librespot[9036]: r2 0x130 Mar 13 16:59:07 rivoplus go-librespot[9036]: r3 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r4 0xf3318330 Mar 13 16:59:07 rivoplus go-librespot[9036]: r5 0xf3318330 Mar 13 16:59:07 rivoplus go-librespot[9036]: r6 0xf33182a0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r7 0x2e12600 Mar 13 16:59:07 rivoplus go-librespot[9036]: r8 0xbc6020 Mar 13 16:59:07 rivoplus go-librespot[9036]: r9 0x3155f84 Mar 13 16:59:07 rivoplus go-librespot[9036]: r10 0xbc5778 Mar 13 16:59:07 rivoplus go-librespot[9036]: fp 0x8 Mar 13 16:59:07 rivoplus go-librespot[9036]: ip 0xf72165b8 Mar 13 16:59:07 rivoplus go-librespot[9036]: sp 0xffccd1b8 Mar 13 16:59:07 rivoplus go-librespot[9036]: lr 0xf71d49df Mar 13 16:59:07 rivoplus go-librespot[9036]: pc 0xf71d58b8 Mar 13 16:59:07 rivoplus go-librespot[9036]: cpsr 0x60070030 Mar 13 16:59:07 rivoplus go-librespot[9036]: fault 0x10 Mar 13 16:59:07 rivoplus go-librespot[9036]: ----- Mar 13 16:59:07 rivoplus go-librespot[9036]: SIGQUIT: quit Mar 13 16:59:07 rivoplus go-librespot[9036]: PC=0x91ed8 m=2 sigcode=0 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 0 gp=0x2c026c8 m=2 mp=0x2c48588 [idle]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futex(0xbc7dac, 0x80, 0x0, 0xf6c3fd60, 0x0, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/sys_linux_arm.s:417 +0x20 fp=0xf6c3fd40 sp=0xf6c3fd40 pc=0x91ed8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futexsleep(0xbc7dac, 0x0, 0x4abd42741) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/os_linux.go:75 +0x178 fp=0xf6c3fd68 sp=0xf6c3fd40 pc=0x52ae4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.notetsleep_internal(0xbc7dac, 0x4abd42741) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/lock_futex.go:212 +0x124 fp=0xf6c3fd90 sp=0xf6c3fd68 pc=0x22814 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.notetsleep(0xbc7dac, 0x4abd42741) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/lock_futex.go:235 +0x48 fp=0xf6c3fda4 sp=0xf6c3fd90 pc=0x229b4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.sysmon() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:6001 +0x2e0 fp=0xf6c3fe00 sp=0xf6c3fda4 pc=0x67960 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mstart1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1729 +0x7c fp=0xf6c3fe10 sp=0xf6c3fe00 pc=0x5d2a4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mstart0() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1686 +0x7c fp=0xf6c3fe24 sp=0xf6c3fe10 pc=0x5d218 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mstart() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:210 +0x8 fp=0xf6c3fe28 sp=0xf6c3fe24 pc=0x8f160 Mar 13 16:59:07 rivoplus go-librespot[9036]: trap 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: error 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: oldmask 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r0 0xfffffffc Mar 13 16:59:07 rivoplus go-librespot[9036]: r1 0x80 Mar 13 16:59:07 rivoplus go-librespot[9036]: r2 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r3 0xf6c3fd60 Mar 13 16:59:07 rivoplus go-librespot[9036]: r4 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r5 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r6 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r7 0xf0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r8 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r9 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r10 0x2c026c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: fp 0x3b9aca00 Mar 13 16:59:07 rivoplus go-librespot[9036]: ip 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: sp 0xf6c3fd40 Mar 13 16:59:07 rivoplus go-librespot[9036]: lr 0x52ae4 Mar 13 16:59:07 rivoplus go-librespot[9036]: pc 0x91ed8 Mar 13 16:59:07 rivoplus go-librespot[9036]: cpsr 0x60070010 Mar 13 16:59:07 rivoplus go-librespot[9036]: fault 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: ----- Mar 13 16:59:07 rivoplus go-librespot[9036]: SIGQUIT: quit Mar 13 16:59:07 rivoplus go-librespot[9036]: PC=0x91ed4 m=3 sigcode=0 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 0 gp=0x2c02a28 m=3 mp=0x2c48b08 [idle]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futex(0x2c48bd0, 0x80, 0x0, 0x0, 0x0, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf643eca4 sp=0xf643eca4 pc=0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futexsleep(0x2c48bd0, 0x0, 0xffffffffffffffff) Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf643eccc sp=0xf643eca4 pc=0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.notesleep(0x2c48bd0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf643ecec sp=0xf643eccc pc=0x22690 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mPark(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1761 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.stopm() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf643ed00 sp=0xf643ecec pc=0x5ee74 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gcstopm() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3081 +0xd4 fp=0xf643ed18 sp=0xf643ed00 pc=0x5fb2c Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.findRunnable() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3149 +0x48 fp=0xf643edc4 sp=0xf643ed18 pc=0x5fd58 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.schedule() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf643ede0 sp=0xf643edc4 pc=0x62064 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.exitsyscall0(0x2da50e8) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:4756 +0x270 fp=0xf643ee08 sp=0xf643ede0 pc=0x64384 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mcall(0xf643f450) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf643ee10 sp=0xf643ee08 pc=0x8f1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: trap 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: error 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: oldmask 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r0 0x2c48bd0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r1 0x80 Mar 13 16:59:07 rivoplus go-librespot[9036]: r2 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r3 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r4 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r5 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r6 0x2da5130 Mar 13 16:59:07 rivoplus go-librespot[9036]: r7 0xf0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r8 0x2c48b08 Mar 13 16:59:07 rivoplus go-librespot[9036]: r9 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r10 0x2c02a28 Mar 13 16:59:07 rivoplus go-librespot[9036]: fp 0x6 Mar 13 16:59:07 rivoplus go-librespot[9036]: ip 0x2c168c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: sp 0xf643eca4 Mar 13 16:59:07 rivoplus go-librespot[9036]: lr 0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: pc 0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: cpsr 0xa00d0010 Mar 13 16:59:07 rivoplus go-librespot[9036]: fault 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: ----- Mar 13 16:59:07 rivoplus go-librespot[9036]: SIGQUIT: quit Mar 13 16:59:07 rivoplus go-librespot[9036]: PC=0x91ed4 m=4 sigcode=0 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 0 gp=0x2c02ea8 m=4 mp=0x2c49088 [idle]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futex(0x2c49150, 0x80, 0x0, 0x0, 0x0, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf5c3dcc4 sp=0xf5c3dcc4 pc=0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futexsleep(0x2c49150, 0x0, 0xffffffffffffffff) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf5c3dcec sp=0xf5c3dcc4 pc=0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.notesleep(0x2c49150) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf5c3dd0c sp=0xf5c3dcec pc=0x22690 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mPark(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1761 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.stopm() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf5c3dd20 sp=0xf5c3dd0c pc=0x5ee74 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.findRunnable() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf5c3ddcc sp=0xf5c3dd20 pc=0x60b14 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.schedule() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf5c3dde8 sp=0xf5c3ddcc pc=0x62064 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.park_m(0x2c02c68) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xf5c3de08 sp=0xf5c3dde8 pc=0x628dc Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mcall(0xf5c3e450) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf5c3de10 sp=0xf5c3de08 pc=0x8f1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: trap 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: error 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: oldmask 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r0 0x2c49150 Mar 13 16:59:07 rivoplus go-librespot[9036]: r1 0x80 Mar 13 16:59:07 rivoplus go-librespot[9036]: r2 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r3 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r4 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r5 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r6 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r7 0xf0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r8 0x1 Mar 13 16:59:07 rivoplus go-librespot[9036]: r9 0xbc7d20 Mar 13 16:59:07 rivoplus go-librespot[9036]: r10 0x2c02ea8 Mar 13 16:59:07 rivoplus go-librespot[9036]: fp 0x6 Mar 13 16:59:07 rivoplus go-librespot[9036]: ip 0xb555aff0 Mar 13 16:59:07 rivoplus go-librespot[9036]: sp 0xf5c3dcc4 Mar 13 16:59:07 rivoplus go-librespot[9036]: lr 0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: pc 0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: cpsr 0xa00d0010 Mar 13 16:59:07 rivoplus go-librespot[9036]: fault 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: ----- Mar 13 16:59:07 rivoplus go-librespot[9036]: SIGQUIT: quit Mar 13 16:59:07 rivoplus go-librespot[9036]: PC=0x91ed4 m=5 sigcode=0 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 0 gp=0x2c030e8 m=5 mp=0x2c49608 [idle]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futex(0x2c496d0, 0x80, 0x0, 0x0, 0x0, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf52fecc0 sp=0xf52fecc0 pc=0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futexsleep(0x2c496d0, 0x0, 0xffffffffffffffff) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf52fece8 sp=0xf52fecc0 pc=0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.notesleep(0x2c496d0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf52fed08 sp=0xf52fece8 pc=0x22690 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mPark(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1761 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.stopm() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf52fed1c sp=0xf52fed08 pc=0x5ee74 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.findRunnable() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf52fedc8 sp=0xf52fed1c pc=0x60b14 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.schedule() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf52fede4 sp=0xf52fedc8 pc=0x62064 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goschedImpl(0x2c02b48, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:4065 +0x198 fp=0xf52fedfc sp=0xf52fede4 pc=0x62a8c Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gosched_m(0x2c02b48) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:4070 +0x24 fp=0xf52fee08 sp=0xf52fedfc pc=0x62b00 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mcall(0xf52ff450) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf52fee10 sp=0xf52fee08 pc=0x8f1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: trap 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: error 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: oldmask 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r0 0x2c496d0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r1 0x80 Mar 13 16:59:07 rivoplus go-librespot[9036]: r2 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r3 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r4 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r5 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r6 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r7 0xf0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r8 0x1 Mar 13 16:59:07 rivoplus go-librespot[9036]: r9 0xbc7d20 Mar 13 16:59:07 rivoplus go-librespot[9036]: r10 0x2c030e8 Mar 13 16:59:07 rivoplus go-librespot[9036]: fp 0x6 Mar 13 16:59:07 rivoplus go-librespot[9036]: ip 0x977bcdd4 Mar 13 16:59:07 rivoplus go-librespot[9036]: sp 0xf52fecc0 Mar 13 16:59:07 rivoplus go-librespot[9036]: lr 0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: pc 0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: cpsr 0xa0000010 Mar 13 16:59:07 rivoplus go-librespot[9036]: fault 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: ----- Mar 13 16:59:07 rivoplus go-librespot[9036]: SIGQUIT: quit Mar 13 16:59:07 rivoplus go-librespot[9036]: PC=0x91ed4 m=6 sigcode=0 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 0 gp=0x2c8e128 m=6 mp=0x2c8a008 [idle]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futex(0xbf07d4, 0x80, 0x0, 0x0, 0x0, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf46fedac sp=0xf46fedac pc=0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futexsleep(0xbf07d4, 0x0, 0xffffffffffffffff) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf46fedd4 sp=0xf46fedac pc=0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.notesleep(0xbf07d4) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf46fedf4 sp=0xf46fedd4 pc=0x22690 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.templateThread() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:2760 +0x90 fp=0xf46fee00 sp=0xf46fedf4 pc=0x5ed2c Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mstart1() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1729 +0x7c fp=0xf46fee10 sp=0xf46fee00 pc=0x5d2a4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mstart0() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1686 +0x7c fp=0xf46fee24 sp=0xf46fee10 pc=0x5d218 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mstart() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:210 +0x8 fp=0xf46fee28 sp=0xf46fee24 pc=0x8f160 Mar 13 16:59:07 rivoplus go-librespot[9036]: trap 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: error 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: oldmask 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r0 0xbf07d4 Mar 13 16:59:07 rivoplus go-librespot[9036]: r1 0x80 Mar 13 16:59:07 rivoplus go-librespot[9036]: r2 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r3 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r4 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r5 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r6 0x1 Mar 13 16:59:07 rivoplus go-librespot[9036]: r7 0xf0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r8 0xf46ff450 Mar 13 16:59:07 rivoplus go-librespot[9036]: r9 0xf46ff4c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r10 0x2c8e128 Mar 13 16:59:07 rivoplus go-librespot[9036]: fp 0x6 Mar 13 16:59:07 rivoplus go-librespot[9036]: ip 0x7 Mar 13 16:59:07 rivoplus go-librespot[9036]: sp 0xf46fedac Mar 13 16:59:07 rivoplus go-librespot[9036]: lr 0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: pc 0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: cpsr 0xa0070010 Mar 13 16:59:07 rivoplus go-librespot[9036]: fault 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: ----- Mar 13 16:59:07 rivoplus go-librespot[9036]: SIGQUIT: quit Mar 13 16:59:07 rivoplus go-librespot[9036]: PC=0x91ed4 m=7 sigcode=0 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 0 gp=0x2c8e908 m=7 mp=0x2c8b608 [idle]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futex(0x2c8b6d0, 0x80, 0x0, 0x0, 0x0, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf3cfecac sp=0xf3cfecac pc=0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futexsleep(0x2c8b6d0, 0x0, 0xffffffffffffffff) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf3cfecd4 sp=0xf3cfecac pc=0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.notesleep(0x2c8b6d0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf3cfecf4 sp=0xf3cfecd4 pc=0x22690 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mPark(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1761 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.stopm() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf3cfed08 sp=0xf3cfecf4 pc=0x5ee74 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.gcstopm() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3081 +0xd4 fp=0xf3cfed20 sp=0xf3cfed08 pc=0x5fb2c Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.findRunnable() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3149 +0x48 fp=0xf3cfedcc sp=0xf3cfed20 pc=0x5fd58 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.schedule() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf3cfede8 sp=0xf3cfedcc pc=0x62064 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.park_m(0x2eeeb48) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xf3cfee08 sp=0xf3cfede8 pc=0x628dc Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mcall(0xf3cff450) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf3cfee10 sp=0xf3cfee08 pc=0x8f1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: trap 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: error 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: oldmask 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r0 0x2c8b6d0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r1 0x80 Mar 13 16:59:07 rivoplus go-librespot[9036]: r2 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r3 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r4 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r5 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r6 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r7 0xf0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r8 0x1 Mar 13 16:59:07 rivoplus go-librespot[9036]: r9 0xbc7d20 Mar 13 16:59:07 rivoplus go-librespot[9036]: r10 0x2c8e908 Mar 13 16:59:07 rivoplus go-librespot[9036]: fp 0x6 Mar 13 16:59:07 rivoplus go-librespot[9036]: ip 0xb0ff06b8 Mar 13 16:59:07 rivoplus go-librespot[9036]: sp 0xf3cfecac Mar 13 16:59:07 rivoplus go-librespot[9036]: lr 0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: pc 0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: cpsr 0xa00d0010 Mar 13 16:59:07 rivoplus go-librespot[9036]: fault 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: ----- Mar 13 16:59:07 rivoplus go-librespot[9036]: SIGQUIT: quit Mar 13 16:59:07 rivoplus go-librespot[9036]: PC=0x91ed4 m=8 sigcode=0 Mar 13 16:59:07 rivoplus go-librespot[9036]: goroutine 0 gp=0x3102368 m=8 mp=0x2c8c108 [idle]: Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futex(0x2c8c1d0, 0x80, 0x0, 0x0, 0x0, 0x0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf32fecdc sp=0xf32fecdc pc=0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.futexsleep(0x2c8c1d0, 0x0, 0xffffffffffffffff) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf32fed04 sp=0xf32fecdc pc=0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.notesleep(0x2c8c1d0) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf32fed24 sp=0xf32fed04 pc=0x22690 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mPark(...) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:1761 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.stopm() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf32fed38 sp=0xf32fed24 pc=0x5ee74 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.findRunnable() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf32fede4 sp=0xf32fed38 pc=0x60b14 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.schedule() Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf32fee00 sp=0xf32fede4 pc=0x62064 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.goexit0(0x2eeeb48) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/proc.go:4181 +0x20 fp=0xf32fee08 sp=0xf32fee00 pc=0x62f24 Mar 13 16:59:07 rivoplus go-librespot[9036]: runtime.mcall(0xf32ff450) Mar 13 16:59:07 rivoplus go-librespot[9036]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf32fee10 sp=0xf32fee08 pc=0x8f1c0 Mar 13 16:59:07 rivoplus go-librespot[9036]: trap 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: error 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: oldmask 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r0 0x2c8c1d0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r1 0x80 Mar 13 16:59:07 rivoplus go-librespot[9036]: r2 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r3 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r4 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r5 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r6 0x0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r7 0xf0 Mar 13 16:59:07 rivoplus go-librespot[9036]: r8 0x1 Mar 13 16:59:07 rivoplus go-librespot[9036]: r9 0xbc7d20 Mar 13 16:59:07 rivoplus go-librespot[9036]: r10 0x3102368 Mar 13 16:59:07 rivoplus go-librespot[9036]: fp 0x6 Mar 13 16:59:07 rivoplus go-librespot[9036]: ip 0x60d1f236 Mar 13 16:59:07 rivoplus go-librespot[9036]: sp 0xf32fecdc Mar 13 16:59:07 rivoplus go-librespot[9036]: lr 0x529c8 Mar 13 16:59:07 rivoplus go-librespot[9036]: pc 0x91ed4 Mar 13 16:59:07 rivoplus go-librespot[9036]: cpsr 0xa0060010 Mar 13 16:59:07 rivoplus go-librespot[9036]: fault 0x0 Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:07 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:07 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 16:59:07 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:07 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:07 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:07 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:07 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:07 rivoplus sudo[11934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:07 rivoplus sudo[11934]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:07 rivoplus sudo[11934]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:07 rivoplus sudo[11938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:07 rivoplus sudo[11938]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:07 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:07 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:08 rivoplus go-librespot[9036]: Aborted Mar 13 16:59:08 rivoplus systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 13 16:59:08 rivoplus systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 13 16:59:08 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:08 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:08 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:08 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:08 rivoplus sudo[11942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:08 rivoplus sudo[11942]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:08 rivoplus sudo[11938]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus sudo[11942]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus sudo[11959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:08 rivoplus sudo[11959]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:08 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:08 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:08 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:08 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:08 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:08 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:08 rivoplus sudo[11959]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus sudo[11971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:08 rivoplus sudo[11971]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus sudo[11971]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus sudo[11980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:08 rivoplus sudo[11980]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:08 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:08 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:08 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:08 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:08 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:08 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:08 rivoplus sudo[11980]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus volumio[3189]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 13 16:59:08 rivoplus volumio[3189]: info: Preparing to save Alsa Options, stopping services first Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::volumioPause Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreStateMachine::pause Mar 13 16:59:08 rivoplus volumio[3189]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"}} Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 16:59:08 rivoplus sudo[11993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:08 rivoplus sudo[11993]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus sudo[11993]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus sudo[12001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:08 rivoplus sudo[12001]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:08 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:08 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:08 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:08 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:08 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:08 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:08 rivoplus sudo[12001]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:08 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 16:59:08 rivoplus volumio[3189]: info: Found match in Cards Database for ignoring default Mixer Mar 13 16:59:08 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:08 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:08 rivoplus volumio[3189]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Mar 13 16:59:08 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 13 16:59:08 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 13 16:59:08 rivoplus volumio[3189]: info: Ignoring Mixers Options Mar 13 16:59:08 rivoplus volumio[3189]: info: Device 5 does not have any Mixer Control Available Mar 13 16:59:08 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 13 16:59:08 rivoplus sudo[12020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:08 rivoplus sudo[12020]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 13 16:59:08 rivoplus sudo[12020]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus sudo[12023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:08 rivoplus sudo[12023]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus sudo[12023]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:08 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 13 16:59:08 rivoplus volumio[3189]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 13 16:59:08 rivoplus volumio[3189]: info: Disabling external Volume Control Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreStateMachine::pushState Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:59:08 rivoplus volumio[3189]: info: CoreCommandRouter::volumioPushState Mar 13 16:59:08 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:59:08 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output Mar 13 16:59:08 rivoplus sudo[12030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:08 rivoplus sudo[12030]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus volumio[3189]: error: Failed to send command to Spotify local API: /player/pause: Error: socket hang up Mar 13 16:59:08 rivoplus sudo[12033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:08 rivoplus sudo[12033]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:08 rivoplus sudo[12030]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:08 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:08 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:08 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:08 rivoplus sudo[12033]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:08 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:08 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:09 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:09 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:09 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:09 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:09 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:09 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:09 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:09 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:09 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:09 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:09 rivoplus volumio[3189]: info: Connection to go-librespot Websocket closed Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:09 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:09 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 13 16:59:09 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:09 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Mar 13 16:59:09 rivoplus volumio[3189]: info: Asound.conf file written Mar 13 16:59:09 rivoplus sudo[12115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 13 16:59:09 rivoplus sudo[12115]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:09 rivoplus sudo[12115]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:09 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Mar 13 16:59:09 rivoplus kernel: Err: can not set rate to 4278967296! Mar 13 16:59:09 rivoplus kernel: Range[3920000 - 500000000] Mar 13 16:59:09 rivoplus kernel: out of value, fixed it Mar 13 16:59:09 rivoplus kernel: id=0 set inskew=0 Mar 13 16:59:09 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:09 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:09 rivoplus volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Mar 13 16:59:09 rivoplus volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Mar 13 16:59:09 rivoplus volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Mar 13 16:59:09 rivoplus volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Mar 13 16:59:09 rivoplus volumio[3189]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Mar 13 16:59:09 rivoplus volumio[3189]: info: Output device has changed, restarting MPD Mar 13 16:59:09 rivoplus sudo[12120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 16:59:09 rivoplus sudo[12120]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:09 rivoplus sudo[12120]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:09 rivoplus volumio[3189]: info: Output device has changed, restarting Shairport Sync Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:09 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 16:59:09 rivoplus sudo[12123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 16:59:09 rivoplus sudo[12123]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:09 rivoplus systemd[1]: Stopping Music Player Daemon... Mar 13 16:59:10 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:10 rivoplus volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 16:59:10 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:10 rivoplus volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:10 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:10 rivoplus systemd[1]: mpd.service: Succeeded. Mar 13 16:59:10 rivoplus systemd[1]: Stopped Music Player Daemon. Mar 13 16:59:10 rivoplus systemd[1]: Starting Music Player Daemon... Mar 13 16:59:10 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 16:59:10 rivoplus volumio[3189]: info: Preparing to generate the ALSA configuration file Mar 13 16:59:10 rivoplus sudo[12134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:10 rivoplus sudo[12134]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus volumio[3189]: info: Output device has changed, restarting MPD Mar 13 16:59:10 rivoplus sudo[12134]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus sudo[12139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 16:59:10 rivoplus sudo[12139]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus volumio[3189]: info: Output device has changed, restarting Shairport Sync Mar 13 16:59:10 rivoplus sudo[12139]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 16:59:10 rivoplus sudo[12142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 16:59:10 rivoplus sudo[12142]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus sudo[12133]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 16:59:10 rivoplus sudo[12133]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus sudo[12133]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus systemd[1]: mpd.service: Succeeded. Mar 13 16:59:10 rivoplus systemd[1]: Stopped Music Player Daemon. Mar 13 16:59:10 rivoplus systemd[1]: Starting Music Player Daemon... Mar 13 16:59:10 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:10 rivoplus volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 16:59:10 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:10 rivoplus volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:10 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:10 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 16:59:10 rivoplus sudo[12154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:10 rivoplus sudo[12154]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus sudo[12149]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 16:59:10 rivoplus sudo[12149]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus sudo[12149]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus sudo[12154]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus sudo[12158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:10 rivoplus sudo[12158]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Mar 13 16:59:10 rivoplus volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 13 16:59:10 rivoplus volumio[3189]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 13 16:59:10 rivoplus volumio[3189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 13 16:59:10 rivoplus volumio[3189]: info: Reading ALSA contributions from plugins. Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:10 rivoplus sudo[12162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:10 rivoplus sudo[12162]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus sudo[12158]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus sudo[12162]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:10 rivoplus sudo[12170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:10 rivoplus sudo[12170]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus sudo[12170]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus sudo[12174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:10 rivoplus sudo[12174]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:10 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:10 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:10 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:10 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:10 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:10 rivoplus sudo[12174]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:10 rivoplus sudo[12178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:10 rivoplus sudo[12178]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus sudo[12178]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:10 rivoplus sudo[12186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:10 rivoplus sudo[12186]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:10 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:10 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:10 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:10 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:10 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:10 rivoplus sudo[12186]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:11 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:11 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:11 rivoplus sudo[12198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:11 rivoplus sudo[12198]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:11 rivoplus systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 13 16:59:11 rivoplus systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 13 16:59:11 rivoplus systemd[1]: Stopped go-librespot Daemon. Mar 13 16:59:11 rivoplus systemd[1]: Started go-librespot Daemon. Mar 13 16:59:11 rivoplus sudo[12198]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:11 rivoplus go-librespot[12205]: go-librespot daemon starting... Mar 13 16:59:11 rivoplus sudo[12206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:11 rivoplus sudo[12206]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:11 rivoplus go-librespot[12205]: time="2025-03-13T16:59:11+01:00" level=info msg="running go-librespot 0.2.0" Mar 13 16:59:11 rivoplus go-librespot[12205]: time="2025-03-13T16:59:11+01:00" level=debug msg="app state loaded" Mar 13 16:59:11 rivoplus go-librespot[12205]: time="2025-03-13T16:59:11+01:00" level=debug msg="stored credentials not found" Mar 13 16:59:11 rivoplus go-librespot[12205]: time="2025-03-13T16:59:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 13 16:59:11 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:11 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:11 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:11 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:11 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:11 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:11 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:11 rivoplus sudo[12206]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:11 rivoplus sudo[12224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:11 rivoplus sudo[12224]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:11 rivoplus sudo[12224]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:11 rivoplus sudo[12230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:11 rivoplus sudo[12230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:11 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:11 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:11 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:11 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:11 rivoplus volumio[3189]: info: MPD Permissions set Mar 13 16:59:11 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:11 rivoplus volumio[3189]: info: MPD Permissions set Mar 13 16:59:11 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:11 rivoplus sudo[12230]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:11 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:11 rivoplus go-librespot[12205]: time="2025-03-13T16:59:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 13 16:59:11 rivoplus go-librespot[12205]: time="2025-03-13T16:59:11+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 13 16:59:11 rivoplus go-librespot[12205]: time="2025-03-13T16:59:11+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 13 16:59:11 rivoplus go-librespot[12205]: time="2025-03-13T16:59:11+01:00" level=info msg="zeroconf server listening on port 42179" Mar 13 16:59:11 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:11 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:11 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:11 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:11 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:11 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:11 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreStateMachine::getcurrentVolume Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::volumioRetrievevolume Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreStateMachine::pushState Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:59:11 rivoplus volumio[3189]: info: CoreCommandRouter::volumioPushState Mar 13 16:59:11 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:59:12 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:12 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:12 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:12 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:12 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:12 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:12 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:12 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:12 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:12 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:12 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:12 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:12 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:12 rivoplus volumio[3189]: info: Initializing connection to go-librespot Websocket Mar 13 16:59:12 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:12 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:12 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:12 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:12 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:12 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:12 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:12 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:12 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:12 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:12 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:12 rivoplus sudo[12319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 13 16:59:12 rivoplus sudo[12319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Mar 13 16:59:12 rivoplus sudo[12319]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:12 rivoplus sudo[12322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 13 16:59:12 rivoplus sudo[12322]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 13 16:59:12 rivoplus sudo[12322]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:12 rivoplus go-librespot[12205]: time="2025-03-13T16:59:12+01:00" level=debug msg="new websocket client" Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:12 rivoplus volumio[3189]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:12 rivoplus sudo[12341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 13 16:59:12 rivoplus sudo[12341]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreStateMachine::getcurrentVolume Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::volumioRetrievevolume Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreStateMachine::pushState Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:59:12 rivoplus volumio[3189]: info: CoreCommandRouter::volumioPushState Mar 13 16:59:13 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:59:13 rivoplus mpd[12163]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 16:59:13 rivoplus mpd[12163]: output: No 'audio_output' defined in config file Mar 13 16:59:13 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output Mar 13 16:59:13 rivoplus sudo[12341]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus sudo[12344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 13 16:59:13 rivoplus sudo[12344]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus kernel: aml_tdm_open Mar 13 16:59:13 rivoplus kernel: Not init audio effects Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:13 rivoplus mpd[12163]: output: Successfully detected a sndio audio device Mar 13 16:59:13 rivoplus kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Mar 13 16:59:13 rivoplus kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Mar 13 16:59:13 rivoplus kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Mar 13 16:59:13 rivoplus kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Mar 13 16:59:13 rivoplus kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501a6c18, id(1), clksel(1) Mar 13 16:59:13 rivoplus kernel: aml_dai_set_tdm_fmt(), fmt not change Mar 13 16:59:13 rivoplus kernel: dump_pcm_setting(ffffffc0501a6c18) Mar 13 16:59:13 rivoplus kernel: pcm_mode(1) Mar 13 16:59:13 rivoplus kernel: sysclk(12288000) Mar 13 16:59:13 rivoplus kernel: sysclk_bclk_ratio(4) Mar 13 16:59:13 rivoplus kernel: bclk(3072000) Mar 13 16:59:13 rivoplus kernel: bclk_lrclk_ratio(64) Mar 13 16:59:13 rivoplus kernel: lrclk(48000) Mar 13 16:59:13 rivoplus kernel: tx_mask(0x3) Mar 13 16:59:13 rivoplus kernel: rx_mask(0x3) Mar 13 16:59:13 rivoplus kernel: slots(2) Mar 13 16:59:13 rivoplus kernel: slot_width(32) Mar 13 16:59:13 rivoplus kernel: lane_mask_in(0x2) Mar 13 16:59:13 rivoplus kernel: lane_mask_out(0x1) Mar 13 16:59:13 rivoplus kernel: lane_oe_mask_in(0x0) Mar 13 16:59:13 rivoplus kernel: lane_oe_mask_out(0x0) Mar 13 16:59:13 rivoplus kernel: lane_lb_mask_in(0x0) Mar 13 16:59:13 rivoplus kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Mar 13 16:59:13 rivoplus kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Mar 13 16:59:13 rivoplus kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Mar 13 16:59:13 rivoplus kernel: aml_dai_set_clkdiv, div 4, clksel(1) Mar 13 16:59:13 rivoplus kernel: aml_dai_set_bclk_ratio, select I2S mode Mar 13 16:59:13 rivoplus kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Mar 13 16:59:13 rivoplus kernel: aml_tdm_prepare(), reset fddr Mar 13 16:59:13 rivoplus kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Mar 13 16:59:13 rivoplus kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Mar 13 16:59:13 rivoplus kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Mar 13 16:59:13 rivoplus kernel: tdm playback mute: 0, lane_cnt = 8 Mar 13 16:59:13 rivoplus kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Mar 13 16:59:13 rivoplus kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Mar 13 16:59:13 rivoplus kernel: tdm playback mute: 1, lane_cnt = 8 Mar 13 16:59:13 rivoplus kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Mar 13 16:59:13 rivoplus mpd[12163]: zeroconf: No global port, disabling zeroconf Mar 13 16:59:13 rivoplus systemd[1]: Started Music Player Daemon. Mar 13 16:59:13 rivoplus sudo[12123]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus sudo[12142]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus sudo[12344]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:13 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:13 rivoplus sudo[12356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:13 rivoplus sudo[12356]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus sudo[12356]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus sudo[12360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:13 rivoplus sudo[12360]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:13 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:13 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:13 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:13 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:13 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:13 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:13 rivoplus sudo[12360]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus sudo[12364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:13 rivoplus sudo[12364]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus sudo[12364]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus sudo[12374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:13 rivoplus sudo[12374]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:13 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:13 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:13 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:13 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:13 rivoplus sudo[12374]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:13 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:13 rivoplus sudo[12386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:13 rivoplus sudo[12386]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:13 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:13 rivoplus sudo[12386]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus sudo[12395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:13 rivoplus sudo[12395]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:13 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:13 rivoplus sudo[12397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:13 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:13 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:13 rivoplus sudo[12397]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:13 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:13 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:13 rivoplus volumio[3189]: info: Connection to go-librespot Websocket established Mar 13 16:59:13 rivoplus sudo[12395]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:13 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:13 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:13 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:13 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:13 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:13 rivoplus sudo[12397]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus sudo[12405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:13 rivoplus sudo[12405]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:13 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:13 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:13 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:13 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:13 rivoplus volumio[3189]: info: Executing endpoint tc_getconfig Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 13 16:59:13 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:13 rivoplus vtcs[12323]: STARTING TidalConnect services, version: 1.4.0.34 Mar 13 16:59:13 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:13 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:13 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:13 rivoplus sudo[12405]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:13 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:13 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:13 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:13 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:13 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:13 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:13 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:13 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:13 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:13 rivoplus volumio[3189]: info: Starting Shairport Sync Mar 13 16:59:13 rivoplus vtcs[12323]: STARTED TidalConnect services. Mar 13 16:59:13 rivoplus volumio[3189]: info: Starting Shairport Sync Mar 13 16:59:13 rivoplus sudo[12437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 16:59:13 rivoplus sudo[12437]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:14 rivoplus volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 13 16:59:14 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 13 16:59:14 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 16:59:14 rivoplus volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 13 16:59:14 rivoplus volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Mar 13 16:59:14 rivoplus sudo[12440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 16:59:14 rivoplus sudo[12440]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 16:59:14 rivoplus sudo[12437]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:14 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 16:59:14 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:14 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 13 16:59:14 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 16:59:14 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 16:59:14 rivoplus sudo[12440]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:14 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:14 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:14 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:14 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:14 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:14 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:14 rivoplus volumio[3189]: error: MPD error: The expression evaluated to a falsy value: Mar 13 16:59:14 rivoplus volumio[3189]: assert.ok(self.idling) Mar 13 16:59:14 rivoplus volumio[3189]: error: The expression evaluated to a falsy value: Mar 13 16:59:14 rivoplus volumio[3189]: assert.ok(self.idling) Mar 13 16:59:14 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:14 rivoplus volumio[3189]: info: Executing endpoint tc_connect Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 13 16:59:14 rivoplus volumio[3189]: info: Connecting to TidalConnect Mar 13 16:59:14 rivoplus volumio[3189]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:14 rivoplus volumio[3189]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:14 rivoplus volumio[3189]: info: Shairport-Sync Started Mar 13 16:59:14 rivoplus volumio[3189]: info: Shairport-Sync Started Mar 13 16:59:14 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:14 rivoplus volumio[3189]: info: Asound.conf file unchanged, so no further update is needed Mar 13 16:59:14 rivoplus volumio[3189]: info: Output device has changed, restarting MPD Mar 13 16:59:14 rivoplus sudo[12458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 13 16:59:14 rivoplus sudo[12458]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus sudo[12458]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:14 rivoplus sudo[12461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 16:59:14 rivoplus sudo[12461]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus volumio[3189]: info: Output device has changed, restarting Shairport Sync Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus sudo[12461]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 16:59:14 rivoplus sudo[12465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 16:59:14 rivoplus sudo[12465]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus systemd[1]: Stopping Music Player Daemon... Mar 13 16:59:14 rivoplus systemd[1]: mpd.service: Succeeded. Mar 13 16:59:14 rivoplus systemd[1]: Stopped Music Player Daemon. Mar 13 16:59:14 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:14 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:14 rivoplus volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 16:59:14 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:14 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:14 rivoplus volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus systemd[1]: Starting Music Player Daemon... Mar 13 16:59:14 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus vtcs[12323]: [2025-03-13 16:59:14.578] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Mar 13 16:59:14 rivoplus vtcs[12323]: [2025-03-13 16:59:14.579] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 13 16:59:14 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 16:59:14 rivoplus sudo[12475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:14 rivoplus sudo[12475]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus volumio[3189]: error: updateQueue error: null Mar 13 16:59:14 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Mar 13 16:59:14 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:14 rivoplus volumio[3189]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:14 rivoplus volumio[3189]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:14 rivoplus systemd[1]: vtcs.service: Succeeded. Mar 13 16:59:14 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Mar 13 16:59:14 rivoplus sudo[12478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 16:59:14 rivoplus sudo[12478]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus sudo[12471]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 16:59:14 rivoplus sudo[12471]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus sudo[12475]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:14 rivoplus sudo[12471]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:14 rivoplus sudo[12478]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:14 rivoplus sudo[12483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 13 16:59:14 rivoplus sudo[12483]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:14 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:14 rivoplus volumio[3189]: info: MPD Permissions set Mar 13 16:59:14 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:14 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:14 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:14 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:14 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Mar 13 16:59:14 rivoplus sudo[12483]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:14 rivoplus volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 13 16:59:14 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:14 rivoplus volumio[3189]: info: Starting Shairport Sync Mar 13 16:59:15 rivoplus sudo[12497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 16:59:15 rivoplus sudo[12497]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:15 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 16:59:15 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:15 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 13 16:59:15 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 16:59:15 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 16:59:15 rivoplus sudo[12497]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:15 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:15 rivoplus volumio[3189]: info: Shairport-Sync Started Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 16:59:15 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 16:59:15 rivoplus volumio[3189]: info: Executing endpoint tc_getconfig Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:15 rivoplus vtcs[12487]: STARTING TidalConnect services, version: 1.4.0.34 Mar 13 16:59:15 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:15 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 13 16:59:15 rivoplus sudo[12514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:15 rivoplus sudo[12514]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:15 rivoplus vtcs[12487]: STARTED TidalConnect services. Mar 13 16:59:15 rivoplus sudo[12514]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:15 rivoplus sudo[12525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:15 rivoplus sudo[12525]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:15 rivoplus volumio[3189]: No protocol specified Mar 13 16:59:15 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 16:59:15 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:15 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:15 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:15 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:15 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:15 rivoplus sudo[12525]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:15 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:15 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:15 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:15 rivoplus volumio[3189]: info: Executing endpoint tc_connect Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 13 16:59:15 rivoplus volumio[3189]: info: Connecting to TidalConnect Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::servicePushState Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreStateMachine::pushState Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::volumioPushState Mar 13 16:59:15 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:59:15 rivoplus sudo[12529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 13 16:59:15 rivoplus sudo[12529]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:15 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output Mar 13 16:59:15 rivoplus volumio[3189]: info: CorePlayQueue::getTrack 0 Mar 13 16:59:15 rivoplus volumio[3189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::servicePushState Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreStateMachine::pushState Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::volumioPushState Mar 13 16:59:15 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:59:15 rivoplus sudo[12529]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:15 rivoplus volumio[3189]: info: MRS: Pushing multiroomSync output Mar 13 16:59:15 rivoplus volumio[3189]: info: CorePlayQueue::getTrack 0 Mar 13 16:59:15 rivoplus volumio[3189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Mar 13 16:59:15 rivoplus sudo[12546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 13 16:59:15 rivoplus sudo[12546]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:15 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:15 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:15 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:15 rivoplus volumio[3189]: info: Signalling Playback active due to playback status change Mar 13 16:59:15 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:15 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:15 rivoplus systemd[1]: Stopping RAAT DAEMON... Mar 13 16:59:15 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 13 16:59:15 rivoplus volumio[3189]: info: TidalConnect service started! Mar 13 16:59:15 rivoplus systemd[1]: raat-daemon.service: Succeeded. Mar 13 16:59:15 rivoplus systemd[1]: Stopped RAAT DAEMON. Mar 13 16:59:15 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:15 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 16:59:15 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 16:59:15 rivoplus systemd[1]: Started RAAT DAEMON. Mar 13 16:59:15 rivoplus sudo[12546]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:15 rivoplus volumio[3189]: info: Raat Daemon started successfully Mar 13 16:59:15 rivoplus volumio[3189]: info: Executing endpoint restartRAATSocket Mar 13 16:59:15 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 13 16:59:15 rivoplus volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 13 16:59:16 rivoplus volumio[3189]: info: TidalConnect service started! Mar 13 16:59:16 rivoplus volumio[3189]: info: TidalConnect service started! Mar 13 16:59:16 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:16 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:16 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:16 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:16 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:16 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:16 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:16 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:16 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:16 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:16 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:16 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:16 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:16 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:16 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:16 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:16 rivoplus volumio[3189]: info: Getting Spotify volume Mar 13 16:59:16 rivoplus volumio[3189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Mar 13 16:59:16 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 16:59:16 rivoplus mpd[12485]: Mar 13 16:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 13 16:59:16 rivoplus systemd[1]: Started Music Player Daemon. Mar 13 16:59:16 rivoplus sudo[12465]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:16 rivoplus volumio[3189]: error: updateQueue error: null Mar 13 16:59:17 rivoplus volumio[3189]: info: TidalConnect service started! Mar 13 16:59:17 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:17 rivoplus volumio[3189]: info: TidalConnect service stoped! Mar 13 16:59:17 rivoplus volumio[3189]: info: TidalConnect service started! Mar 13 16:59:17 rivoplus volumio[3189]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:17 rivoplus volumio[3189]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 13 16:59:17 rivoplus sudo[12572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 13 16:59:17 rivoplus sudo[12572]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:59:17 rivoplus sudo[12572]: pam_unix(sudo:session): session closed for user root Mar 13 16:59:18 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:18 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:18 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:18 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:18 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:18 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:18 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:18 rivoplus volumio[3189]: info: RAAT: Requesting Headphone Status Mar 13 16:59:18 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 13 16:59:18 rivoplus volumio[3189]: info: MCU Signalled Headphone Mode Disabled Mar 13 16:59:18 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 13 16:59:18 rivoplus volumio[3189]: info: Reporting Headphone State: false Mar 13 16:59:18 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:18 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 16:59:18 rivoplus volumio[3189]: info: Updating RAAT Signal Path Mar 13 16:59:20 rivoplus volumio[3189]: info: TidalConnect service started! Mar 13 16:59:23 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 16:59:25 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:25 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:59:25 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:25 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:59:25 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:25 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:59:26 rivoplus volumio[3189]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 16:59:26 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 13 16:59:26 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 13 16:59:26 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 13 16:59:26 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 13 16:59:26 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Mar 13 16:59:27 rivoplus volumio[3189]: info: Received Get System Version Mar 13 16:59:27 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 16:59:33 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:59:33 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:59:33 rivoplus volumio[3189]: info: Discovery: Getting this device information Mar 13 16:59:33 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 16:59:33 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:59:40 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 16:59:43 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:43 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:59:43 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:43 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:59:43 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:43 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:59:44 rivoplus volumio[3189]: error: Failed request for metavolumio API Mar 13 16:59:54 rivoplus volumio[3189]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 16:59:54 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 13 16:59:54 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 13 16:59:54 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 13 16:59:54 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 13 16:59:54 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Mar 13 16:59:54 rivoplus volumio[3189]: info: Received Get System Version Mar 13 16:59:54 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 16:59:57 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 16:59:59 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 16:59:59 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 13 16:59:59 rivoplus volumio[3189]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 16:59:59 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:59 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:59:59 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:59 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:59:59 rivoplus volumio[3189]: info: Executing endpoint metavolumio Mar 13 16:59:59 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 17:00:03 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 17:00:03 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 17:00:03 rivoplus volumio[3189]: info: Discovery: Getting this device information Mar 13 17:00:03 rivoplus volumio[3189]: info: CoreCommandRouter::volumioGetState Mar 13 17:00:03 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 17:00:09 rivoplus volumio[3189]: info: Enabling plugin fusiondsp Mar 13 17:00:09 rivoplus volumio[3189]: info: Loading plugin "fusiondsp"... Mar 13 17:00:09 rivoplus volumio[3189]: info: Preparing to generate the ALSA configuration file Mar 13 17:00:09 rivoplus volumio[3189]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 13 17:00:09 rivoplus volumio[3189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 13 17:00:09 rivoplus volumio[3189]: info: Reading ALSA contributions from plugins. Mar 13 17:00:09 rivoplus volumio[3189]: info: Asound.conf file unchanged, so no further update is needed Mar 13 17:00:09 rivoplus volumio[3189]: info: Output device has changed, restarting MPD Mar 13 17:00:09 rivoplus sudo[12730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 17:00:09 rivoplus sudo[12730]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:09 rivoplus sudo[12730]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:10 rivoplus volumio[3189]: info: Output device has changed, restarting Shairport Sync Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:00:10 rivoplus sudo[12733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 17:00:10 rivoplus sudo[12733]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus systemd[1]: Stopping Music Player Daemon... Mar 13 17:00:10 rivoplus volumio[3189]: No protocol specified Mar 13 17:00:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 17:00:10 rivoplus volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 17:00:10 rivoplus volumio[3189]: No protocol specified Mar 13 17:00:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 17:00:10 rivoplus volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus systemd[1]: mpd.service: Succeeded. Mar 13 17:00:10 rivoplus systemd[1]: Stopped Music Player Daemon. Mar 13 17:00:10 rivoplus systemd[1]: Starting Music Player Daemon... Mar 13 17:00:10 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus vtcs[12487]: [2025-03-13 17:00:10.143] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 13 17:00:10 rivoplus vtcs[12487]: [2025-03-13 17:00:10.147] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 13 17:00:10 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 17:00:10 rivoplus volumio[3189]: info: PLUGIN START: fusiondsp Mar 13 17:00:10 rivoplus volumio[3189]: info: Loading i18n strings for locale de Mar 13 17:00:10 rivoplus sudo[12750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 17:00:10 rivoplus sudo[12750]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: FusionDsp - mixtype--------------------- None Mar 13 17:00:10 rivoplus volumio[3189]: info: Preparing to generate the ALSA configuration file Mar 13 17:00:10 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Mar 13 17:00:10 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 13 17:00:10 rivoplus systemd[1]: vtcs.service: Succeeded. Mar 13 17:00:10 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Mar 13 17:00:10 rivoplus volumio[3189]: info: Done. Mar 13 17:00:10 rivoplus sudo[12750]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:10 rivoplus volumio[3189]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 13 17:00:10 rivoplus volumio[3189]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 13 17:00:10 rivoplus volumio[3189]: info: Reading ALSA contributions from plugins. Mar 13 17:00:10 rivoplus sudo[12739]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 17:00:10 rivoplus sudo[12739]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 17:00:10 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 17:00:10 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 17:00:10 rivoplus sudo[12739]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:10 rivoplus sudo[12754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 17:00:10 rivoplus sudo[12754]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 17:00:10 rivoplus volumio[3189]: info: MPD Permissions set Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus sudo[12754]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 13 17:00:10 rivoplus volumio[3189]: info: Starting Shairport Sync Mar 13 17:00:10 rivoplus volumio[3189]: info: Asound.conf file unchanged, so no further update is needed Mar 13 17:00:10 rivoplus volumio[3189]: info: Output device has changed, restarting MPD Mar 13 17:00:10 rivoplus sudo[12766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 13 17:00:10 rivoplus sudo[12766]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 13 17:00:10 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 13 17:00:10 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 13 17:00:10 rivoplus sudo[12769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 13 17:00:10 rivoplus sudo[12769]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 13 17:00:10 rivoplus sudo[12766]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:10 rivoplus volumio[3189]: info: Output device has changed, restarting Shairport Sync Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 13 17:00:10 rivoplus sudo[12769]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:10 rivoplus sudo[12773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 13 17:00:10 rivoplus sudo[12773]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 13 17:00:10 rivoplus systemd[1]: mpd.service: Succeeded. Mar 13 17:00:10 rivoplus systemd[1]: Stopped Music Player Daemon. Mar 13 17:00:10 rivoplus systemd[1]: Starting Music Player Daemon... Mar 13 17:00:10 rivoplus volumio[3189]: No protocol specified Mar 13 17:00:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 17:00:10 rivoplus volumio[3189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 13 17:00:10 rivoplus volumio[3189]: No protocol specified Mar 13 17:00:10 rivoplus volumio[3189]: xcb_connection_has_error() returned true Mar 13 17:00:10 rivoplus volumio[3189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 17:00:10 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:10 rivoplus volumio[3189]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 13 17:00:10 rivoplus sudo[12786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 17:00:10 rivoplus sudo[12786]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus sudo[12782]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 13 17:00:10 rivoplus sudo[12782]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus sudo[12786]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:10 rivoplus sudo[12782]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:10 rivoplus sudo[12790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 13 17:00:10 rivoplus sudo[12790]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 17:00:10 rivoplus sudo[12790]: pam_unix(sudo:session): session closed for user root Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 13 17:00:11 rivoplus volumio[3189]: info: Not Reporting Auto name since its the default one Mar 13 17:00:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 17:00:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 17:00:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 17:00:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 17:00:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 13 17:00:11 rivoplus volumio[3189]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 13 17:00:11 rivoplus volumio[3189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 17:00:11 rivoplus volumio[3189]: TypeError: Cannot read property 'off' of undefined Mar 13 17:00:11 rivoplus volumio[3189]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Mar 13 17:00:11 rivoplus volumio[3189]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Mar 13 17:00:11 rivoplus volumio[3189]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Mar 13 17:00:11 rivoplus volumio[3189]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Mar 13 17:00:11 rivoplus volumio[3189]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Mar 13 17:00:11 rivoplus volumio[3189]: at Socket.emit (events.js:400:28) Mar 13 17:00:11 rivoplus volumio[3189]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 13 17:00:11 rivoplus volumio[3189]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 13 17:00:11 rivoplus volumio[3189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 17:00:12 rivoplus sudo[12813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 16:59 Mar 13 17:00:12 rivoplus sudo[12813]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:29:52 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c26baf739f7d1f3fe648e6cf51b2c348"