-- Logs begin at Thu 2019-02-14 11:12:01 CET, end at Thu 2024-10-03 19:09:12 CEST. -- Oct 03 19:08:00 volumio go-librespot[2128]: time="2024-10-03T19:08:00+02:00" level=debug msg="update volume to 59377/65535" Oct 03 19:08:00 volumio go-librespot[2128]: time="2024-10-03T19:08:00+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:00 volumio go-librespot[2128]: time="2024-10-03T19:08:00+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:00 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":91,"max":100}} Oct 03 19:08:00 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 91 Oct 03 19:08:00 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 91 Oct 03 19:08:00 volumio volumio[947]: info: VolumeController::SetAlsaVolume91 Oct 03 19:08:00 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:00 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:00 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:00 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":91,"max":100}} Oct 03 19:08:00 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 91 Oct 03 19:08:00 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Oct 03 19:08:00 volumio volumio[947]: info: Selecting previously unselected package python3-yarl. Oct 03 19:08:00 volumio volumio[947]: info: Preparing to unpack .../31-python3-yarl_1.3.0-1_armhf.deb ... Oct 03 19:08:00 volumio volumio[947]: info: Unpacking python3-yarl (1.3.0-1) ... Oct 03 19:08:01 volumio volumio[947]: info: Selecting previously unselected package python3-async-timeout. Oct 03 19:08:01 volumio volumio[947]: info: Preparing to unpack .../32-python3-async-timeout_3.0.1-1_all.deb ... Oct 03 19:08:01 volumio volumio[947]: info: Unpacking python3-async-timeout (3.0.1-1) ... Oct 03 19:08:01 volumio go-librespot[2128]: time="2024-10-03T19:08:01+02:00" level=debug msg="update volume to 61780/65535" Oct 03 19:08:01 volumio volumio[947]: info: Selecting previously unselected package python3-attr. Oct 03 19:08:01 volumio volumio[947]: info: Preparing to unpack .../33-python3-attr_18.2.0-1_all.deb ... Oct 03 19:08:01 volumio volumio[947]: info: Unpacking python3-attr (18.2.0-1) ... Oct 03 19:08:01 volumio go-librespot[2128]: time="2024-10-03T19:08:01+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:01 volumio go-librespot[2128]: time="2024-10-03T19:08:01+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:01 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":95,"max":100}} Oct 03 19:08:01 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 95 Oct 03 19:08:01 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 95 Oct 03 19:08:01 volumio volumio[947]: info: VolumeController::SetAlsaVolume95 Oct 03 19:08:01 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:01 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:01 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:01 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":95,"max":100}} Oct 03 19:08:01 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 95 Oct 03 19:08:01 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=debug msg="update volume to 59107/65535" Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":91,"max":100}} Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 91 Oct 03 19:08:02 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 91 Oct 03 19:08:02 volumio volumio[947]: info: VolumeController::SetAlsaVolume91 Oct 03 19:08:02 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":91,"max":100}} Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 91 Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Oct 03 19:08:02 volumio volumio[947]: info: Selecting previously unselected package python3-aiohttp. Oct 03 19:08:02 volumio volumio[947]: info: Preparing to unpack .../34-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... Oct 03 19:08:02 volumio volumio[947]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=debug msg="update volume to 57230/65535" Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":88,"max":100}} Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 88 Oct 03 19:08:02 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 88 Oct 03 19:08:02 volumio volumio[947]: info: VolumeController::SetAlsaVolume88 Oct 03 19:08:02 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":88,"max":100}} Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 88 Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=debug msg="update volume to 38897/65535" Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":60,"max":100}} Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 60 Oct 03 19:08:02 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 60 Oct 03 19:08:02 volumio volumio[947]: info: VolumeController::SetAlsaVolume60 Oct 03 19:08:02 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":60,"max":100}} Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 60 Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=debug msg="update volume to 21225/65535" Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:02 volumio go-librespot[2128]: time="2024-10-03T19:08:02+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":33,"max":100}} Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 33 Oct 03 19:08:02 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 33 Oct 03 19:08:02 volumio volumio[947]: info: VolumeController::SetAlsaVolume33 Oct 03 19:08:02 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:02 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":33,"max":100}} Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 33 Oct 03 19:08:02 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=debug msg="update volume to 19904/65535" Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":31,"max":100}} Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 31 Oct 03 19:08:03 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 31 Oct 03 19:08:03 volumio volumio[947]: info: VolumeController::SetAlsaVolume31 Oct 03 19:08:03 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":31,"max":100}} Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 31 Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=debug msg="update volume to 48644/65535" Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Oct 03 19:08:03 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 75 Oct 03 19:08:03 volumio volumio[947]: info: VolumeController::SetAlsaVolume75 Oct 03 19:08:03 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=debug msg="update volume to 64714/65535" Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":99,"max":100}} Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 99 Oct 03 19:08:03 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 99 Oct 03 19:08:03 volumio volumio[947]: info: VolumeController::SetAlsaVolume99 Oct 03 19:08:03 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":99,"max":100}} Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 99 Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=debug msg="update volume to 65535/65535" Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:03 volumio go-librespot[2128]: time="2024-10-03T19:08:03+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Oct 03 19:08:03 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 100 Oct 03 19:08:03 volumio volumio[947]: info: VolumeController::SetAlsaVolume100 Oct 03 19:08:03 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:03 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Oct 03 19:08:03 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 19:08:04 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 19:08:04 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 19:08:05 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 19:08:06 volumio volumio[947]: info: Selecting previously unselected package python3-asn1crypto. Oct 03 19:08:06 volumio volumio[947]: info: Preparing to unpack .../35-python3-asn1crypto_0.24.0-1_all.deb ... Oct 03 19:08:06 volumio volumio[947]: info: Unpacking python3-asn1crypto (0.24.0-1) ... Oct 03 19:08:07 volumio go-librespot[2128]: time="2024-10-03T19:08:07+02:00" level=debug msg="update volume to 64032/65535" Oct 03 19:08:07 volumio go-librespot[2128]: time="2024-10-03T19:08:07+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:07 volumio go-librespot[2128]: time="2024-10-03T19:08:07+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:07 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":98,"max":100}} Oct 03 19:08:07 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 98 Oct 03 19:08:07 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 98 Oct 03 19:08:07 volumio volumio[947]: info: VolumeController::SetAlsaVolume98 Oct 03 19:08:07 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:07 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:07 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:07 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":98,"max":100}} Oct 03 19:08:07 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 98 Oct 03 19:08:07 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98 Oct 03 19:08:08 volumio go-librespot[2128]: time="2024-10-03T19:08:08+02:00" level=debug msg="fetched chunk 12/44, size: 524288" uri="spotify:track:6BGxbBw5J314z6BDxbEanm" Oct 03 19:08:10 volumio go-librespot[2128]: time="2024-10-03T19:08:10+02:00" level=debug msg="update volume to 53896/65535" Oct 03 19:08:10 volumio go-librespot[2128]: time="2024-10-03T19:08:10+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:10 volumio go-librespot[2128]: time="2024-10-03T19:08:10+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":83,"max":100}} Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 83 Oct 03 19:08:10 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 83 Oct 03 19:08:10 volumio volumio[947]: info: VolumeController::SetAlsaVolume83 Oct 03 19:08:10 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:10 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:10 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":83,"max":100}} Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 83 Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Oct 03 19:08:10 volumio go-librespot[2128]: time="2024-10-03T19:08:10+02:00" level=debug msg="update volume to 56467/65535" Oct 03 19:08:10 volumio go-librespot[2128]: time="2024-10-03T19:08:10+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:10 volumio go-librespot[2128]: time="2024-10-03T19:08:10+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":87,"max":100}} Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 87 Oct 03 19:08:10 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 87 Oct 03 19:08:10 volumio volumio[947]: info: VolumeController::SetAlsaVolume87 Oct 03 19:08:10 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:10 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:10 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":87,"max":100}} Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 87 Oct 03 19:08:10 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Oct 03 19:08:11 volumio go-librespot[2128]: time="2024-10-03T19:08:11+02:00" level=debug msg="update volume to 64566/65535" Oct 03 19:08:11 volumio go-librespot[2128]: time="2024-10-03T19:08:11+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 03 19:08:11 volumio go-librespot[2128]: time="2024-10-03T19:08:11+02:00" level=trace msg="emitting websocket event: volume" Oct 03 19:08:11 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":99,"max":100}} Oct 03 19:08:11 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 99 Oct 03 19:08:11 volumio volumio[947]: info: Setting Volumio Volume from Spotify: 99 Oct 03 19:08:11 volumio volumio[947]: info: VolumeController::SetAlsaVolume99 Oct 03 19:08:11 volumio volumio[947]: info: CoreStateMachine::pushState Oct 03 19:08:11 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 19:08:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Oct 03 19:08:11 volumio volumio[947]: SPOTIFY: received: {"type":"volume","data":{"value":99,"max":100}} Oct 03 19:08:11 volumio volumio[947]: SPOTIFY: RECEIVED SPOTIFY VOLUME 99 Oct 03 19:08:11 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 03 19:08:11 volumio volumio[947]: info: Selecting previously unselected package python3-cffi-backend. Oct 03 19:08:11 volumio volumio[947]: info: Preparing to unpack .../36-python3-cffi-backend_1.12.2-1_armhf.deb ... Oct 03 19:08:11 volumio volumio[947]: info: Unpacking python3-cffi-backend (1.12.2-1) ... Oct 03 19:08:13 volumio volumio[947]: info: Selecting previously unselected package python3-crypto. Oct 03 19:08:13 volumio volumio[947]: info: Preparing to unpack .../37-python3-crypto_2.6.1-9+b1_armhf.deb ... Oct 03 19:08:13 volumio volumio[947]: info: Unpacking python3-crypto (2.6.1-9+b1) ... Oct 03 19:08:17 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 19:08:22 volumio go-librespot[2128]: time="2024-10-03T19:08:22+02:00" level=debug msg="fetched chunk 13/44, size: 524288" uri="spotify:track:6BGxbBw5J314z6BDxbEanm" Oct 03 19:08:23 volumio volumio[947]: info: Selecting previously unselected package python3-cryptography. Oct 03 19:08:23 volumio volumio[947]: info: Preparing to unpack .../38-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... Oct 03 19:08:23 volumio volumio[947]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... Oct 03 19:08:28 volumio volumio[947]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Oct 03 19:08:28 volumio volumio[947]: info: Selecting previously unselected package python3-dbus. Oct 03 19:08:28 volumio volumio[947]: info: Preparing to unpack .../39-python3-dbus_1.2.8-3_armhf.deb ... Oct 03 19:08:28 volumio volumio[947]: info: Unpacking python3-dbus (1.2.8-3) ... Oct 03 19:08:29 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 19:08:30 volumio volumio[947]: info: Selecting previously unselected package python3.7-dev. Oct 03 19:08:30 volumio volumio[947]: info: Preparing to unpack .../40-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... Oct 03 19:08:30 volumio volumio[947]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... Oct 03 19:08:30 volumio volumio[947]: info: Selecting previously unselected package python3-dev. Oct 03 19:08:30 volumio volumio[947]: info: Preparing to unpack .../41-python3-dev_3.7.3-1_armhf.deb ... Oct 03 19:08:30 volumio volumio[947]: info: Unpacking python3-dev (3.7.3-1) ... Oct 03 19:08:30 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 19:08:30 volumio volumio[947]: info: Selecting previously unselected package python3-entrypoints. Oct 03 19:08:30 volumio volumio[947]: info: Preparing to unpack .../42-python3-entrypoints_0.3-1_all.deb ... Oct 03 19:08:30 volumio volumio[947]: info: Unpacking python3-entrypoints (0.3-1) ... Oct 03 19:08:30 volumio volumio[947]: info: Selecting previously unselected package python3-gi. Oct 03 19:08:30 volumio volumio[947]: info: Preparing to unpack .../43-python3-gi_3.30.4-1_armhf.deb ... Oct 03 19:08:30 volumio volumio[947]: info: Unpacking python3-gi (3.30.4-1) ... Oct 03 19:08:31 volumio volumio[947]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Oct 03 19:08:31 volumio volumio[947]: info: Folder /tmp/plugins removed Oct 03 19:08:32 volumio volumio[947]: info: Check plugin dependencies Oct 03 19:08:32 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 19:08:32 volumio volumio[947]: info: Checking if plugin already exists Oct 03 19:08:32 volumio volumio[947]: info: Rename folder Oct 03 19:08:32 volumio volumio[947]: info: Folder /tmp/downloaded_plugin.zip removed Oct 03 19:08:33 volumio volumio[947]: info: Move to category Oct 03 19:08:35 volumio go-librespot[2128]: time="2024-10-03T19:08:35+02:00" level=debug msg="fetched chunk 14/44, size: 524288" uri="spotify:track:6BGxbBw5J314z6BDxbEanm" Oct 03 19:08:49 volumio go-librespot[2128]: time="2024-10-03T19:08:49+02:00" level=debug msg="fetched chunk 15/44, size: 524288" uri="spotify:track:6BGxbBw5J314z6BDxbEanm" Oct 03 19:08:49 volumio volumio-remote-updater[686]: [2024-10-03 17:08:49] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 03 19:08:54 volumio volumio-remote-updater[686]: [2024-10-03 17:08:54] [connect] Successful connection Oct 03 19:08:56 volumio volumio[947]: info: Checking if install.sh is present Oct 03 19:08:57 volumio volumio[947]: info: Executing install.sh Oct 03 19:08:57 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 03 19:08:57 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 03 19:08:57 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 03 19:08:57 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 03 19:08:57 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 03 19:08:57 volumio volumio-remote-updater[686]: [2024-10-03 17:08:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727975334 101 Oct 03 19:08:57 volumio volumio[947]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Oct 03 19:08:57 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 03 19:08:57 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 03 19:08:57 volumio sudo[3767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Oct 03 19:08:57 volumio sudo[3767]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: Received Get System Info Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 19:08:57 volumio volumio[947]: info: Discovery: Getting this device information Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: Received Get System Info Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 19:08:57 volumio volumio[947]: info: Discovery: Getting this device information Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 19:08:57 volumio volumio[947]: info: Received Get System Info Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 19:08:57 volumio volumio[947]: info: Discovery: Getting this device information Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: Listing playlists Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Oct 03 19:08:57 volumio volumio[947]: info: CoreStateMachine::getQueue Oct 03 19:08:57 volumio volumio[947]: info: CorePlayQueue::getQueue Oct 03 19:08:57 volumio volumio[947]: info: Received Get System Info Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 19:08:57 volumio volumio[947]: info: Discovery: Getting this device information Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:57 volumio volumio[947]: info: creating filters folder and copying demo filters Oct 03 19:08:57 volumio volumio[947]: info: creating filters folder and copying demo filters Oct 03 19:08:57 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 03 19:08:58 volumio sudo[3778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 19:08:58 volumio sudo[3778]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:08:58 volumio volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 03 19:08:58 volumio volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 03 19:08:58 volumio sudo[3781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 19:08:58 volumio sudo[3781]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:58 volumio volumio[947]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Oct 03 19:08:58 volumio volumio[947]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 03 19:08:58 volumio sudo[3781]: pam_unix(sudo:session): session closed for user root Oct 03 19:08:58 volumio sudo[3778]: pam_unix(sudo:session): session closed for user root Oct 03 19:08:58 volumio volumio[947]: info: copying demo flters Oct 03 19:08:58 volumio volumio[947]: info: copying demo flters Oct 03 19:08:58 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.6 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 19:08:58 volumio volumio[947]: info: Received Get System Info Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 19:08:58 volumio volumio[947]: info: Discovery: Getting this device information Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:58 volumio volumio[947]: info: Listing playlists Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 19:08:58 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 03 19:08:59 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 19:08:59 volumio volumio[947]: info: Received Get System Info Oct 03 19:08:59 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 19:08:59 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 19:08:59 volumio volumio[947]: info: Discovery: Getting this device information Oct 03 19:08:59 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:08:59 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 19:08:59 volumio volumio[947]: info: Installing/fusiondsp dependencies Oct 03 19:08:59 volumio volumio[947]: info: Installing/fusiondsp dependencies Oct 03 19:08:59 volumio sudo[3795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Oct 03 19:08:59 volumio sudo[3795]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:09:00 volumio volumio[947]: info: Get:1 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] Oct 03 19:09:00 volumio volumio[947]: info: Get:1 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] Oct 03 19:09:00 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 03 19:09:00 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 19:09:00 volumio volumio[947]: info: Received Get System Info Oct 03 19:09:00 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 19:09:00 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 19:09:00 volumio volumio[947]: info: Discovery: Getting this device information Oct 03 19:09:00 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Oct 03 19:09:00 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 19:09:03 volumio go-librespot[2128]: time="2024-10-03T19:09:03+02:00" level=debug msg="fetched chunk 16/44, size: 524288" uri="spotify:track:6BGxbBw5J314z6BDxbEanm" Oct 03 19:09:04 volumio volumio[947]: info: Enabling plugin fusiondsp Oct 03 19:09:04 volumio volumio[947]: info: Loading plugin "fusiondsp"... Oct 03 19:09:06 volumio volumio[947]: info: Preparing to generate the ALSA configuration file Oct 03 19:09:07 volumio volumio[947]: info: Enabling plugin fusiondsp Oct 03 19:09:07 volumio volumio[947]: info: Enabling plugin fusiondsp Oct 03 19:09:07 volumio volumio[947]: info: Loading plugin "fusiondsp"... Oct 03 19:09:07 volumio volumio[947]: info: Applying required configuration parameters for plugin fusiondsp Oct 03 19:09:07 volumio volumio[947]: info: Loading plugin "fusiondsp"... Oct 03 19:09:07 volumio volumio[947]: info: Applying required configuration parameters for plugin fusiondsp Oct 03 19:09:07 volumio volumio[947]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Oct 03 19:09:07 volumio volumio[947]: info: Hit:3 http://archive.raspberrypi.org/debian buster InRelease Oct 03 19:09:07 volumio volumio[947]: info: Oct 03 19:09:07 volumio volumio[947]: Selecting previously unselected package python3-secretstorage. Oct 03 19:09:07 volumio volumio[947]: info: Preparing to unpack .../44-python3-secretstorage_2.3.1-2_all.deb ... Oct 03 19:09:07 volumio volumio[947]: info: Unpacking python3-secretstorage (2.3.1-2) ... Oct 03 19:09:07 volumio volumio[947]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Oct 03 19:09:07 volumio volumio[947]: info: Hit:3 http://archive.raspberrypi.org/debian buster InRelease Oct 03 19:09:07 volumio volumio[947]: info: Oct 03 19:09:07 volumio volumio[947]: Selecting previously unselected package python3-secretstorage. Oct 03 19:09:07 volumio volumio[947]: info: Preparing to unpack .../44-python3-secretstorage_2.3.1-2_all.deb ... Oct 03 19:09:07 volumio volumio[947]: info: Unpacking python3-secretstorage (2.3.1-2) ... Oct 03 19:09:07 volumio volumio[947]: info: Asound.conf file unchanged, so no further update is needed Oct 03 19:09:07 volumio volumio[947]: info: Output device has changed, restarting MPD Oct 03 19:09:08 volumio sudo[3906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 19:09:08 volumio volumio[947]: info: Output device has changed, restarting Shairport Sync Oct 03 19:09:08 volumio sudo[3906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 19:09:08 volumio sudo[3906]: pam_unix(sudo:session): session closed for user root Oct 03 19:09:08 volumio sudo[3914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 19:09:08 volumio sudo[3914]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:09:08 volumio systemd[1]: Stopping Music Player Daemon... Oct 03 19:09:08 volumio volumio[947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 19:09:08 volumio volumio[947]: info: Preparing to generate the ALSA configuration file Oct 03 19:09:08 volumio volumio[947]: info: PLUGIN START: fusiondsp Oct 03 19:09:08 volumio volumio[947]: info: Loading i18n strings for locale fr Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: FusionDsp - mixtype--------------------- Hardware Oct 03 19:09:08 volumio volumio[947]: info: Done. Oct 03 19:09:08 volumio volumio[947]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 03 19:09:08 volumio volumio[947]: info: Reading ALSA contributions from plugins. Oct 03 19:09:08 volumio volumio[947]: info: MPD Permissions set Oct 03 19:09:08 volumio volumio[947]: info: Selecting previously unselected package python3-keyring. Oct 03 19:09:08 volumio volumio[947]: info: Preparing to unpack .../45-python3-keyring_17.1.1-1_all.deb ... Oct 03 19:09:08 volumio volumio[947]: info: Unpacking python3-keyring (17.1.1-1) ... Oct 03 19:09:08 volumio volumio[947]: info: Selecting previously unselected package python3-keyring. Oct 03 19:09:08 volumio volumio[947]: info: Preparing to unpack .../45-python3-keyring_17.1.1-1_all.deb ... Oct 03 19:09:08 volumio volumio[947]: info: Unpacking python3-keyring (17.1.1-1) ... Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:08 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:09 volumio systemd[1]: mpd.service: Succeeded. Oct 03 19:09:09 volumio systemd[1]: Stopped Music Player Daemon. Oct 03 19:09:09 volumio systemd[1]: Starting Music Player Daemon... Oct 03 19:09:09 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 19:09:09 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:09 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 19:09:09 volumio sudo[4028]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 19:09:09 volumio sudo[4028]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:09:09 volumio sudo[4028]: pam_unix(sudo:session): session closed for user root Oct 03 19:09:10 volumio volumio[947]: info: Starting Shairport Sync Oct 03 19:09:10 volumio sudo[4092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 19:09:10 volumio sudo[4092]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:09:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 03 19:09:10 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 03 19:09:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 03 19:09:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 03 19:09:10 volumio sudo[4092]: pam_unix(sudo:session): session closed for user root Oct 03 19:09:10 volumio volumio[947]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 03 19:09:10 volumio volumio[947]: info: camilladsp service started and running in background, instance 1 Oct 03 19:09:10 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 19:09:10 volumio volumio[947]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 03 19:09:10 volumio volumio[947]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 03 19:09:10 volumio volumio[947]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 03 19:09:10 volumio volumio[947]: info: FusionDsp loaded Oct 03 19:09:10 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 19:09:10 volumio sudo[4116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 03 19:09:10 volumio sudo[4116]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:09:10 volumio volumio[947]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 03 19:09:10 volumio volumio[947]: info: Adding Signal Path Element [object Object] Oct 03 19:09:10 volumio volumio[947]: info: Adding fusiondspeq DSP Signal Path Element Oct 03 19:09:10 volumio volumio[947]: info: FusionDsp - ---- installed callbackRead Oct 03 19:09:10 volumio volumio[947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 19:09:10 volumio sudo[4116]: pam_unix(sudo:session): session closed for user root Oct 03 19:09:10 volumio volumio[947]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 03 19:09:10 volumio volumio[947]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 03 19:09:10 volumio volumio[947]: at onErrorNT (internal/child_process.js:465:16) Oct 03 19:09:10 volumio volumio[947]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 03 19:09:10 volumio volumio[947]: at runNextTicks (internal/process/task_queues.js:62:3) Oct 03 19:09:10 volumio volumio[947]: at listOnTimeout (internal/timers.js:523:9) Oct 03 19:09:10 volumio volumio[947]: at processTimers (internal/timers.js:497:7) { Oct 03 19:09:10 volumio volumio[947]: errno: -2, Oct 03 19:09:10 volumio volumio[947]: code: 'ENOENT', Oct 03 19:09:10 volumio volumio[947]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 03 19:09:10 volumio volumio[947]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 03 19:09:10 volumio volumio[947]: spawnargs: [ Oct 03 19:09:10 volumio volumio[947]: '-p', Oct 03 19:09:10 volumio volumio[947]: 9876, Oct 03 19:09:10 volumio volumio[947]: '-o', Oct 03 19:09:10 volumio volumio[947]: '/tmp/camilladsp.log', Oct 03 19:09:10 volumio volumio[947]: '-l', Oct 03 19:09:10 volumio volumio[947]: 'warn', Oct 03 19:09:10 volumio volumio[947]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 03 19:09:10 volumio volumio[947]: ] Oct 03 19:09:10 volumio volumio[947]: } Oct 03 19:09:10 volumio volumio[947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 19:09:11 volumio sudo[4130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-03 19:08 Oct 03 19:09:11 volumio sudo[4130]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 03 19:09:12 volumio mpd[4031]: Oct 03 19:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 03 19:09:12 volumio systemd[1]: Started Music Player Daemon. Oct 03 19:09:12 volumio sudo[3914]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"