Dec 12 12:51:07 volumina ntpd[966]: CLOCK: time stepped by 30.532709 Dec 12 12:51:07 volumina ntpd[966]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 12 12:51:07 volumina volumio[1145]: info: Loading plugin "scheduledrestart"... Dec 12 12:51:08 volumina ntpd[966]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 12 12:51:08 volumina ntpd[966]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 12 12:51:08 volumina ntpd[966]: DNS: Pool taking: 213.206.164.3 Dec 12 12:51:08 volumina ntpd[966]: DNS: Pool taking: 217.91.179.72 Dec 12 12:51:08 volumina ntpd[966]: DNS: Pool taking: 213.239.234.28 Dec 12 12:51:08 volumina ntpd[966]: DNS: Pool taking: 139.162.152.20 Dec 12 12:51:08 volumina ntpd[966]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 12 12:51:09 volumina volumio[1145]: info: Applying required configuration parameters for plugin scheduledrestart Dec 12 12:51:09 volumina volumio[1145]: info: Loading i18n strings for locale de Dec 12 12:51:09 volumina volumio[1145]: Updating browse sources language Dec 12 12:51:09 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:51:09 volumina ntpd[966]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 12 12:51:09 volumina ntpd[966]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 12 12:51:09 volumina ntpd[966]: DNS: Pool taking: 152.53.119.33 Dec 12 12:51:09 volumina ntpd[966]: DNS: Pool taking: 188.34.198.106 Dec 12 12:51:09 volumina ntpd[966]: DNS: Pool taking: 45.145.40.190 Dec 12 12:51:09 volumina ntpd[966]: DNS: Pool skipping: 168.119.211.223 Dec 12 12:51:09 volumina ntpd[966]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 12 12:51:09 volumina volumio[1335]: Starting albumart workers Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::initPlayerControls Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 12:51:10 volumina volumio[1145]: Express server listening on port 3000 Dec 12 12:51:10 volumina volumio[1145]: [Metrics] WebUI: 12s 861.34ms Dec 12 12:51:10 volumina volumio[1145]: info: CoreStateMachine::resetVolumioState Dec 12 12:51:10 volumina volumio[1145]: info: CoreStateMachine::getcurrentVolume Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 12 12:51:10 volumina volumio[1145]: info: CoreStateMachine::pushState Dec 12 12:51:10 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 12:51:10 volumina volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 12 12:51:10 volumina volumio[1145]: info: Cannot read play queue from file Dec 12 12:51:10 volumina volumio[1337]: Starting albumart workers Dec 12 12:51:10 volumina volumio[1145]: info: Volumio Network Manager: Network status updated: 1 Dec 12 12:51:11 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Dec 12 12:51:11 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Dec 12 12:51:11 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Dec 12 12:51:11 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:11 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Dec 12 12:51:11 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Dec 12 12:51:11 volumina volumio[1145]: info: CoreStateMachine::setRepeat null single undefined Dec 12 12:51:11 volumina volumio[1145]: info: CoreStateMachine::pushState Dec 12 12:51:11 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:11 volumina volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 12 12:51:11 volumina volumio[1145]: info: CoreStateMachine::setRandom null Dec 12 12:51:11 volumina volumio[1145]: info: CoreStateMachine::pushState Dec 12 12:51:11 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:11 volumina volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 12 12:51:11 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Dec 12 12:51:11 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Dec 12 12:51:11 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Dec 12 12:51:11 volumina volumio[1145]: info: Setting Device type: Raspberry PI Dec 12 12:51:11 volumina volumio[1145]: info: Completed loading Core Plugins Dec 12 12:51:11 volumina volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 12 12:51:12 volumina volumio[1145]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 12 12:51:12 volumina volumio[1145]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Dec 12 12:51:12 volumina volumio[1145]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 11 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 13 Dec 12 12:51:12 volumina volumio[1145]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 12 12:51:12 volumina volumio[1145]: info: Reading ALSA contributions from plugins. Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 14 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 15 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 16 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 17 Dec 12 12:51:12 volumina sudo[1390]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 12 12:51:12 volumina volumio[1145]: info: Discovery: adding d8138a86-ecf0-49bd-866e-818abc0ff0db Dec 12 12:51:12 volumina volumio[1145]: info: Discovery: Found device Volumina Dec 12 12:51:12 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:12 volumina sudo[1390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:12 volumina sudo[1401]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 12 12:51:12 volumina sudo[1401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:12 volumina sudo[1390]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:12 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:12 volumina sudo[1401]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:12 volumina volumio[1336]: Starting albumart workers Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 18 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 19 Dec 12 12:51:12 volumina volumio[1145]: info: Discovery: this is already registered, d8138a86-ecf0-49bd-866e-818abc0ff0db Dec 12 12:51:12 volumina volumio[1145]: info: Discovery: Found device Volumina Dec 12 12:51:12 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:12 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 20 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 21 Dec 12 12:51:12 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 22 Dec 12 12:51:13 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 23 Dec 12 12:51:13 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 24 Dec 12 12:51:13 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 25 Dec 12 12:51:13 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 26 Dec 12 12:51:13 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 27 Dec 12 12:51:13 volumina volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Dec 12 12:51:13 volumina volumio[1145]: info: Output device has changed, restarting MPD Dec 12 12:51:13 volumina sudo[1406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 12 12:51:13 volumina sudo[1406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:13 volumina volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 12 12:51:13 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:13 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:13 volumina sudo[1411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 12:51:13 volumina sudo[1408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 12:51:13 volumina sudo[1408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:13 volumina sudo[1411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:13 volumina sudo[1408]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:13 volumina volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 12:51:13 volumina volumio[1145]: info: ___________ START PLUGINS ___________ Dec 12 12:51:13 volumina volumio[1145]: info: ControllerMpd::onStart: Initializing MPD Dec 12 12:51:13 volumina systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 12 12:51:13 volumina sudo[1406]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:13 volumina volumio[1145]: info: Creating MPD Configuration file Dec 12 12:51:13 volumina systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 12:51:13 volumina systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 12:51:13 volumina sudo[1437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 12:51:13 volumina sudo[1437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:13 volumina sudo[1437]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:13 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 12 12:51:13 volumina volumio[1145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 12 12:51:13 volumina volumio[1145]: info: [1765540273896] CoreMusicLibrary::Adding element Medienserver Dec 12 12:51:13 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:51:13 volumina sudo[1438]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 12:51:13 volumina sudo[1438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 12:51:13 volumina sudo[1442]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 12 12:51:13 volumina sudo[1438]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:13 volumina sudo[1441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 12:51:13 volumina sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:14 volumina systemd[1]: mpd.service: Deactivated successfully. Dec 12 12:51:14 volumina systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 12:51:14 volumina systemd[1]: mpd.socket: Deactivated successfully. Dec 12 12:51:14 volumina systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 12:51:14 volumina systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 12:51:14 volumina systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 12:51:14 volumina systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 12:51:14 volumina volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:14 volumina volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 12 12:51:14 volumina volumio[1145]: info: [1765540274215] CoreMusicLibrary::Adding element Last_100 Dec 12 12:51:14 volumina sudo[1450]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 12:51:14 volumina sudo[1450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 12 12:51:14 volumina sudo[1455]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 12 12:51:14 volumina sudo[1450]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:14 volumina volumio[1145]: info: [1765540274247] CoreMusicLibrary::Adding element Webradio Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 12:51:14 volumina volumio[1145]: info: Initializing BBC Radios Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:14 volumina volumio[1145]: info: Creating Spotify config file Dec 12 12:51:14 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:15 volumina volumio[1145]: info: FusionDsp - CamillaDSP binary found. Dec 12 12:51:15 volumina volumio[1145]: info: Loading i18n strings for locale de Dec 12 12:51:15 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 12 12:51:15 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:15 volumina volumio[1145]: info: FusionDsp - mixtype--------------------- None Dec 12 12:51:15 volumina volumio[1145]: info: Scheduling reboots with hours: 5, minutes: 0 in timezone: Europe/Berlin Dec 12 12:51:15 volumina volumio[1145]: info: Volumio Calling Home Dec 12 12:51:15 volumina volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 12 12:51:16 volumina volumio-remote-updater[722]: [2025-12-12 12:51:16] [connect] Successful connection Dec 12 12:51:16 volumina systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 12 12:51:18 volumina volumio[1145]: info: camilladsp service started and running in background, instance 1 Dec 12 12:51:18 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:18 volumina volumio[1145]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Dec 12 12:51:18 volumina volumio[1145]: info: FusionDsp loaded Dec 12 12:51:18 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 12:51:18 volumina volumio[1145]: error: FusionDsp - Socket connection not established Dec 12 12:51:18 volumina volumio[1145]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 12 12:51:18 volumina volumio[1145]: info: Adding Signal Path Element [object Object] Dec 12 12:51:18 volumina volumio[1145]: info: Adding fusiondspeq DSP Signal Path Element Dec 12 12:51:18 volumina volumio[1145]: info: FusionDsp - ---- installed callbackRead Dec 12 12:51:18 volumina volumio[1145]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 12 12:51:18 volumina sudo[1492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 12 12:51:18 volumina sudo[1492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:18 volumina volumio[1145]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 12 12:51:18 volumina volumio[1145]: info: Reading ALSA contributions from plugins. Dec 12 12:51:18 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 28 Dec 12 12:51:18 volumina mpd[1456]: 2025-12-12T12:51:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 12:51:18 volumina volumio[1145]: info: MPD Permissions set Dec 12 12:51:18 volumina volumio[1145]: info: MPD Permissions set Dec 12 12:51:18 volumina volumio[1145]: info: Upmpdcli Daemon Started Dec 12 12:51:18 volumina volumio[1145]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 12 12:51:18 volumina volumio-remote-updater[722]: [2025-12-12 12:51:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765540276 101 Dec 12 12:51:18 volumina volumio[1145]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 29 Dec 12 12:51:18 volumina systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Dec 12 12:51:18 volumina systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 12:51:18 volumina sudo[1441]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:18 volumina sudo[1411]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:18 volumina sudo[1492]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:18 volumina volumio[1145]: info: Volumio called home Dec 12 12:51:18 volumina volumio[1145]: info: Spotify config file written Dec 12 12:51:18 volumina sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 12 12:51:18 volumina sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:19 volumina systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 12 12:51:19 volumina systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 12 12:51:19 volumina systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 12 12:51:19 volumina go-librespot[1511]: go-librespot daemon starting... Dec 12 12:51:19 volumina sudo[1509]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:19 volumina volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Dec 12 12:51:19 volumina volumio[1145]: info: Completed starting Core Plugins Dec 12 12:51:19 volumina volumio[1145]: info: ------------------------------------------- Dec 12 12:51:19 volumina volumio[1145]: info: ----- MyVolumio plugins startup ---- Dec 12 12:51:19 volumina volumio[1145]: info: ------------------------------------------- Dec 12 12:51:19 volumina volumio[1145]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 12 12:51:19 volumina volumio[1145]: info: No need to fix Spotify hosts Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina go-librespot[1512]: time="2025-12-12T12:51:19+01:00" level=info msg="running go-librespot 0.4.0" Dec 12 12:51:19 volumina go-librespot[1512]: time="2025-12-12T12:51:19+01:00" level=debug msg="app state loaded" Dec 12 12:51:19 volumina go-librespot[1512]: time="2025-12-12T12:51:19+01:00" level=debug msg="stored credentials not found" Dec 12 12:51:19 volumina go-librespot[1512]: time="2025-12-12T12:51:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 12 12:51:19 volumina systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:19 volumina volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 31 Dec 12 12:51:19 volumina volumio[1145]: info: MPD running with PID1456 Dec 12 12:51:19 volumina volumio[1145]: ,establishing connection Dec 12 12:51:19 volumina go-librespot[1512]: time="2025-12-12T12:51:19+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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 12 12:51:19 volumina go-librespot[1512]: time="2025-12-12T12:51:19+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 12 12:51:19 volumina go-librespot[1512]: time="2025-12-12T12:51:19+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 12 12:51:19 volumina go-librespot[1512]: time="2025-12-12T12:51:19+01:00" level=info msg="zeroconf server listening on port 33747" Dec 12 12:51:20 volumina volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Dec 12 12:51:20 volumina volumio[1145]: assert.ok(self.idling) Dec 12 12:51:20 volumina volumio[1145]: error: The expression evaluated to a falsy value: Dec 12 12:51:20 volumina volumio[1145]: assert.ok(self.idling) Dec 12 12:51:20 volumina volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Dec 12 12:51:20 volumina volumio[1145]: assert.ok(self.idling) Dec 12 12:51:20 volumina volumio[1145]: error: The expression evaluated to a falsy value: Dec 12 12:51:20 volumina volumio[1145]: assert.ok(self.idling) Dec 12 12:51:20 volumina volumio[1145]: info: Starting Shairport Sync Dec 12 12:51:20 volumina volumio[1145]: info: Starting Shairport Sync Dec 12 12:51:20 volumina volumio[1145]: info: Starting Shairport Sync Dec 12 12:51:20 volumina sudo[1538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 12:51:20 volumina sudo[1538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:20 volumina sudo[1540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 12:51:20 volumina sudo[1540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:20 volumina volumio[1145]: error: updateQueue error: null Dec 12 12:51:21 volumina systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 12:51:21 volumina systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 12:51:21 volumina systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:51:21 volumina systemd[1]: shairport-sync.service: Consumed 1.650s CPU time. Dec 12 12:51:21 volumina sudo[1544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 12:51:21 volumina sudo[1544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:21 volumina systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:51:21 volumina sudo[1538]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:21 volumina systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 12:51:21 volumina systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 12:51:21 volumina systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:51:21 volumina systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:51:21 volumina sudo[1540]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:21 volumina volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Dec 12 12:51:21 volumina volumio[1145]: info: Output device has changed, restarting MPD Dec 12 12:51:21 volumina systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 12:51:21 volumina systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 12:51:21 volumina systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:51:21 volumina systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:51:21 volumina sudo[1544]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:21 volumina volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:21 volumina sudo[1562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 12:51:21 volumina sudo[1562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:21 volumina sudo[1565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 12:51:21 volumina sudo[1562]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:21 volumina volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 12:51:21 volumina sudo[1565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:21 volumina volumio[1145]: info: Shairport-Sync Started Dec 12 12:51:21 volumina volumio[1145]: Error adding Membership: Error: addMembership EINVAL Dec 12 12:51:21 volumina volumio[1145]: info: MPD Permissions set Dec 12 12:51:21 volumina systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 12 12:51:21 volumina volumio[1145]: info: Shairport-Sync Started Dec 12 12:51:21 volumina volumio[1145]: info: Shairport-Sync Started Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:21 volumina systemd[1]: mpd.service: Deactivated successfully. Dec 12 12:51:21 volumina systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 12:51:21 volumina systemd[1]: mpd.service: Consumed 3.064s CPU time. Dec 12 12:51:21 volumina systemd[1]: mpd.socket: Deactivated successfully. Dec 12 12:51:21 volumina systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 12:51:21 volumina systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 12:51:21 volumina systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 12:51:21 volumina systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:21 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:51:22 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:22 volumina sudo[1594]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 12:51:22 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:22 volumina sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 12:51:22 volumina sudo[1594]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:22 volumina volumio[1145]: info: Starting Shairport Sync Dec 12 12:51:22 volumina sudo[1599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 12:51:22 volumina sudo[1600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 12:51:22 volumina sudo[1600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:22 volumina sudo[1599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:22 volumina sudo[1602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 12:51:22 volumina sudo[1602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:22 volumina systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 12:51:22 volumina systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 12:51:22 volumina systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:51:22 volumina systemd[1]: shairport-sync.service: Consumed 1.028s CPU time. Dec 12 12:51:22 volumina volumio[1145]: info: go-librespot daemon successfully initialized Dec 12 12:51:22 volumina systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:51:22 volumina volumio[1145]: verbose: New Socket.io Connection to 192.168.8.152 from 192.168.8.178 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 32 Dec 12 12:51:22 volumina sudo[1599]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:23 volumina volumio[1145]: info: Shairport-Sync Started Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:23 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:23 volumina volumio[1145]: info: Received Get System Info Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 12:51:23 volumina volumio[1145]: info: Discovery: Getting this device information Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:23 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:23 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:23 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:23 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 12 12:51:23 volumina volumio[1145]: info: Received Get System Info Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 12:51:23 volumina volumio[1145]: info: Discovery: Getting this device information Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:23 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 12:51:23 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:23 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:23 volumina volumio[1145]: info: Listing playlists Dec 12 12:51:23 volumina mpd[1596]: 2025-12-12T12:51:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 12:51:23 volumina systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 12:51:23 volumina sudo[1565]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:23 volumina systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 12:51:23 volumina mpd_monitor.sh[1637]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 12:51:23 volumina sudo[1600]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:23 volumina sudo[1602]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:24 volumina volumio[1145]: info: Received Get System Info Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 12:51:24 volumina volumio[1145]: info: Discovery: Getting this device information Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:24 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:24 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:24 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:24 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:24 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 12 12:51:25 volumina volumio[1145]: info: Received Get System Info Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 12:51:25 volumina volumio[1145]: info: Discovery: Getting this device information Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:25 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:25 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:25 volumina volumio[1145]: info: Listing playlists Dec 12 12:51:25 volumina volumio[1145]: info: Received Get System Info Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 12:51:25 volumina volumio[1145]: info: Discovery: Getting this device information Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:25 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 12:51:25 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:25 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:26 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:26 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 12 12:51:26 volumina volumio[1145]: info: Received Get System Info Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 12:51:26 volumina volumio[1145]: info: Discovery: Getting this device information Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:26 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:26 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:26 volumina volumio[1145]: info: Listing playlists Dec 12 12:51:26 volumina volumio[1145]: info: Received Get System Info Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 12 12:51:26 volumina volumio[1145]: info: Discovery: Getting this device information Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:26 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 12 12:51:26 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:51:26 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:51:27 volumina volumio[1145]: info: Initializing connection to go-librespot Websocket Dec 12 12:51:27 volumina volumio[1145]: info: Successfully started MPD Monitor Dec 12 12:51:27 volumina volumio[1145]: info: Successfully started MPD Monitor Dec 12 12:51:27 volumina go-librespot[1512]: time="2025-12-12T12:51:27+01:00" level=debug msg="new websocket client" Dec 12 12:51:27 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:27 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:27 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:27 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:28 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:29 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:29 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:29 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:29 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:29 volumina volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:51:29 volumina volumio[1145]: error: updateQueue error: null Dec 12 12:51:29 volumina volumio[1145]: info: Connection to go-librespot Websocket established Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 12 12:51:30 volumina volumio[1145]: info: Adding plugin bluetooth to MyMusic Plugins Dec 12 12:51:30 volumina volumio[1145]: info: Adding plugin multiroom to MyMusic Plugins Dec 12 12:51:30 volumina volumio[1145]: info: Adding plugin metavolumio to MyMusic Plugins Dec 12 12:51:30 volumina volumio[1145]: info: Adding plugin cd_controller to MyMusic Plugins Dec 12 12:51:30 volumina volumio[1145]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 12 12:51:30 volumina volumio[1145]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 12 12:51:30 volumina volumio[1145]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 12 12:51:30 volumina volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 12 12:51:36 volumina volumio[1145]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 12 12:51:36 volumina volumio[1145]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 12 12:51:36 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:36 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:36 volumina volumio[1145]: info: Starting MyVolumio Remote Streaming Endpoints Dec 12 12:51:36 volumina volumio[1145]: info: MyVolumio login type: Token Dec 12 12:51:36 volumina volumio[1145]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 12 12:51:36 volumina volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 12 12:51:37 volumina upmpdcli[1671]: writing RSA key Dec 12 12:51:40 volumina volumio[1145]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 12 12:51:40 volumina volumio[1145]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 12 12:51:40 volumina volumio[1145]: info: Streaming services startup Dec 12 12:51:40 volumina volumio[1145]: info: Starting Streaming Daemon Dec 12 12:51:40 volumina systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 12 12:51:40 volumina volumio[1145]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 12 12:51:40 volumina sudo[1677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 12 12:51:40 volumina sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:40 volumina sudo[1677]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:40 volumina sudo[1697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 12:51:40 volumina sudo[1697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:51:40 volumina systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 12:51:40 volumina systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 12:51:40 volumina systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 12:51:40 volumina volumio[1145]: info: Getting Spotify volume Dec 12 12:51:40 volumina systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 12:51:40 volumina mpd_monitor.sh[1701]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 12:51:40 volumina sudo[1697]: pam_unix(sudo:session): session closed for user root Dec 12 12:51:41 volumina volumio[1145]: info: Successfully started MPD Monitor Dec 12 12:51:41 volumina volumio[1145]: error: Cannot start Volumio Streaming Daemon Dec 12 12:51:41 volumina volumio[1145]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 12 12:51:41 volumina volumio[1145]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 12 12:51:42 volumina volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 33 Dec 12 12:51:45 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 12:51:45 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:51:45 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 12 12:51:46 volumina systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 12 12:51:46 volumina systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="obtained new client token: AACuW5zH0YRu8DYzzntsOo1QN93BMoiAA9LhQTIThVb6DMHJ12Ld6CG8VID/U6tlpar9AFHwTyTkg0Ya2tj69dVuvbBjk97ySMxX5eokrhOxG8R1dDFab1xA4TAJ3e8EJUsbnG/GFERfAvJup9MZFyMbEKlPwMCaM1oKTSba7pX63E2DvXvlP9tDKAKqEzZNL3DfKsbhqfUk7J6/dVfyNiUS/7p/ENmJw3bdht57z9qEvLLNVnzx5cIeoIQ=" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="completed keyexchange" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="completed challenge" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=info msg="authenticated AP" username="hc*********************f3" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=info msg="authenticated Login5" username="hc*********************f3" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=info msg="accepted zeroconf from Pixel 7" username="hc*********************f3" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="dealer connection opened" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=trace msg="starting accesspoint recv loop" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=trace msg="starting dealer recv loop" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=trace msg="received accesspoint ping" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="received connection id: ZDdhZmM1ODYtNDI4...RkZERjMzQkM2NA==" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=trace msg="received accesspoint pong ack" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 12 12:51:50 volumina go-librespot[1512]: time="2025-12-12T12:51:50+01:00" level=debug msg="update volume requested to 48880/65535" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="handling transfer player command from b159c3e88982f1bd7f65c945018339e7124c6d21" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="resolved context of track" uri="spotify:user:hc0ll5j475je7onbsnxz88xf3:collection" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=trace msg="fetched new page 0 with 33 items (list: 33)" uri="spotify:user:hc0ll5j475je7onbsnxz88xf3:collection" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="shuffled context with seed 5970842309597517787 (len: 33, keep: 1)" uri="spotify:user:hc0ll5j475je7onbsnxz88xf3:collection" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="loading track (paused: false, position: 993589ms)" uri="spotify:track:2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=trace msg="emitting websocket event: will_play" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="selected format OGG_VORBIS_320 (9d86f42c4e63206cfccdcf01f9117f6f738ab70d)" uri="spotify:track:2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="requested aes key for file 9d86f42c4e63206cfccdcf01f9117f6f738ab70d, gid: 2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1699" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="fetched first chunk of 14, total size is 7194193 bytes" uri="spotify:track:2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1294" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="fetched chunk 13/13, size: 378449" uri="spotify:track:2Zrv4bCzz8Ix9mGWse1Exc" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed creating stream for spotify:track:2Zrv4bCzz8Ix9mGWse1Exc: failed seeking stream: failed reading page: EOF" Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 12 12:51:51 volumina go-librespot[1512]: time="2025-12-12T12:51:51+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 12 12:52:09 volumina go-librespot[1512]: time="2025-12-12T12:52:09+01:00" level=debug msg="update volume requested to 49535/65535" Dec 12 12:52:09 volumina go-librespot[1512]: time="2025-12-12T12:52:09+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:09 volumina go-librespot[1512]: time="2025-12-12T12:52:09+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:09 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Dec 12 12:52:10 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Dec 12 12:52:10 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 75 Dec 12 12:52:10 volumina volumio[1145]: info: VolumeController::SetAlsaVolume75 Dec 12 12:52:10 volumina volumio[1145]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:hc0ll5j475je7onbsnxz88xf3:collection","uri":"spotify:track:2Zrv4bCzz8Ix9mGWse1Exc","play_origin":"playlist"}} Dec 12 12:52:10 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":76,"max":100}} Dec 12 12:52:10 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 76 Dec 12 12:52:10 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 76 Dec 12 12:52:10 volumina volumio[1145]: info: VolumeController::SetAlsaVolume76 Dec 12 12:52:10 volumina volumio[1145]: info: CoreStateMachine::pushState Dec 12 12:52:10 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 12 12:52:10 volumina volumio[1145]: info: CoreStateMachine::pushState Dec 12 12:52:10 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::volumioGetBrowseSources Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 12:52:10 volumina volumio[1145]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 12 12:52:10 volumina volumio[1145]: info: Completed starting MyVolumio Plugin Dec 12 12:52:10 volumina volumio[1145]: [Metrics] CommandRouter: 71s 588.28ms Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::volumiosetStartupVolume Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::Close All Modals sent Dec 12 12:52:10 volumina volumio[1145]: info: CoreCommandRouter::Close All Modals sent Dec 12 12:52:10 volumina go-librespot[1512]: time="2025-12-12T12:52:10+01:00" level=debug msg="update volume requested to 48880/65535" Dec 12 12:52:10 volumina go-librespot[1512]: time="2025-12-12T12:52:10+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:10 volumina go-librespot[1512]: time="2025-12-12T12:52:10+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:10 volumina go-librespot[1512]: time="2025-12-12T12:52:10+01:00" level=debug msg="update volume requested to 48225/65535" Dec 12 12:52:11 volumina go-librespot[1512]: time="2025-12-12T12:52:11+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:11 volumina go-librespot[1512]: time="2025-12-12T12:52:11+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:11 volumina go-librespot[1512]: time="2025-12-12T12:52:11+01:00" level=debug msg="update volume requested to 46259/65535" Dec 12 12:52:11 volumina go-librespot[1512]: time="2025-12-12T12:52:11+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:11 volumina go-librespot[1512]: time="2025-12-12T12:52:11+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:11 volumina go-librespot[1512]: time="2025-12-12T12:52:11+01:00" level=debug msg="update volume requested to 41016/65535" Dec 12 12:52:11 volumina go-librespot[1512]: time="2025-12-12T12:52:11+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:11 volumina go-librespot[1512]: time="2025-12-12T12:52:11+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:12 volumina go-librespot[1512]: time="2025-12-12T12:52:12+01:00" level=debug msg="update volume requested to 38394/65535" Dec 12 12:52:12 volumina go-librespot[1512]: time="2025-12-12T12:52:12+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:12 volumina go-librespot[1512]: time="2025-12-12T12:52:12+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:13 volumina go-librespot[1512]: time="2025-12-12T12:52:13+01:00" level=debug msg="update volume requested to 37739/65535" Dec 12 12:52:13 volumina go-librespot[1512]: time="2025-12-12T12:52:13+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:13 volumina go-librespot[1512]: time="2025-12-12T12:52:13+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:14 volumina go-librespot[1512]: time="2025-12-12T12:52:14+01:00" level=debug msg="update volume requested to 37084/65535" Dec 12 12:52:14 volumina go-librespot[1512]: time="2025-12-12T12:52:14+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:14 volumina go-librespot[1512]: time="2025-12-12T12:52:14+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:15 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:15 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 50, in query Dec 12 12:52:15 volumina volumio[1507]: rawrepl = self._ws.recv() Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_core.py", line 388, in recv Dec 12 12:52:15 volumina volumio[1507]: opcode, data = self.recv_data() Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_core.py", line 416, in recv_data Dec 12 12:52:15 volumina volumio[1507]: opcode, frame = self.recv_data_frame(control_frame) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_core.py", line 437, in recv_data_frame Dec 12 12:52:15 volumina volumio[1507]: frame = self.recv_frame() Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_core.py", line 478, in recv_frame Dec 12 12:52:15 volumina volumio[1507]: return self.frame_buffer.recv_frame() Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_abnf.py", line 363, in recv_frame Dec 12 12:52:15 volumina volumio[1507]: self.recv_header() Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_abnf.py", line 319, in recv_header Dec 12 12:52:15 volumina volumio[1507]: header = self.recv_strict(2) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_abnf.py", line 398, in recv_strict Dec 12 12:52:15 volumina volumio[1507]: bytes_ = self.recv(min(16384, shortage)) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_core.py", line 563, in _recv Dec 12 12:52:15 volumina volumio[1507]: return recv(self.sock, bufsize) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/websocket/_socket.py", line 132, in recv Dec 12 12:52:15 volumina volumio[1507]: raise WebSocketConnectionClosedException("Connection to remote host was lost.") Dec 12 12:52:15 volumina volumio[1507]: websocket._exceptions.WebSocketConnectionClosedException: Connection to remote host was lost. Dec 12 12:52:15 volumina volumio[1507]: The above exception was the direct cause of the following exception: Dec 12 12:52:15 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:15 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:15 volumina volumio[1507]: return await handler(request) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 192, in get_param_json Dec 12 12:52:15 volumina volumio[1507]: result = cdsp.volume.all() Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 28, in all Dec 12 12:52:15 volumina volumio[1507]: faders = self.client.query("GetFaders") Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 53, in query Dec 12 12:52:15 volumina volumio[1507]: raise IOError("Lost connection to CamillaDSP") from err Dec 12 12:52:15 volumina volumio[1507]: OSError: Lost connection to CamillaDSP Dec 12 12:52:15 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:15 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:15 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:15 volumina volumio[1507]: return await handler(request) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:15 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:15 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:15 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:15 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:15 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:15 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:15 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:15 volumina volumio[1507]: return await handler(request) Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:15 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:15 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:15 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:15 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:15 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:15 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:16 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:16 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:16 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:16 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:16 volumina volumio[1507]: return await handler(request) Dec 12 12:52:16 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:16 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:16 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:16 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:16 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:16 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:16 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:16 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:16 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:16 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:16 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:16 volumina volumio[1507]: return await handler(request) Dec 12 12:52:16 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:16 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:16 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:16 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:16 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:16 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:16 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:16 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:17 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:17 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:17 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:17 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:17 volumina volumio[1507]: return await handler(request) Dec 12 12:52:17 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:17 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:17 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:17 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:17 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:17 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:17 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:17 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:17 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:17 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:17 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:17 volumina volumio[1507]: return await handler(request) Dec 12 12:52:17 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:17 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:17 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:17 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:17 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:17 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:17 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:17 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:18 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 12 12:52:18 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 12 12:52:18 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 12 12:52:18 volumina volumio[1145]: info: BOOT COMPLETED Dec 12 12:52:18 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:18 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:18 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:18 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:18 volumina volumio[1507]: return await handler(request) Dec 12 12:52:18 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:18 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:18 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:18 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:18 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:18 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:18 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:18 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:18 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:18 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:18 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:18 volumina volumio[1507]: return await handler(request) Dec 12 12:52:18 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:18 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:18 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:18 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:18 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:18 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:18 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:18 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:19 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:19 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:19 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:19 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:19 volumina volumio[1507]: return await handler(request) Dec 12 12:52:19 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:19 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:19 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:19 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:19 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:19 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:19 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:19 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:19 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:19 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:19 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:19 volumina volumio[1507]: return await handler(request) Dec 12 12:52:19 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:19 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:19 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:19 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:19 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:19 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:19 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:19 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:20 volumina go-librespot[1512]: time="2025-12-12T12:52:20+01:00" level=trace msg="sent dealer ping" Dec 12 12:52:20 volumina go-librespot[1512]: time="2025-12-12T12:52:20+01:00" level=trace msg="received dealer pong" Dec 12 12:52:20 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:20 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:20 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:20 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:20 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:20 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:20 volumina volumio[1507]: return await handler(request) Dec 12 12:52:20 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:20 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:20 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:20 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:20 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:20 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:20 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:20 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:20 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:20 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:21 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:21 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:21 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:21 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:21 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:21 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:21 volumina volumio[1507]: return await handler(request) Dec 12 12:52:21 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:21 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:21 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:21 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:21 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:21 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:21 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:21 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:21 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:21 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:21 volumina go-librespot[1512]: time="2025-12-12T12:52:21+01:00" level=debug msg="handling pause player command from b159c3e88982f1bd7f65c945018339e7124c6d21" Dec 12 12:52:21 volumina go-librespot[1512]: time="2025-12-12T12:52:21+01:00" level=warning msg="failed handling dealer request" error="no primary stream" Dec 12 12:52:22 volumina go-librespot[1512]: time="2025-12-12T12:52:22+01:00" level=debug msg="handling resume player command from b159c3e88982f1bd7f65c945018339e7124c6d21" Dec 12 12:52:22 volumina go-librespot[1512]: time="2025-12-12T12:52:22+01:00" level=warning msg="failed handling dealer request" error="no primary stream" Dec 12 12:52:22 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:22 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:22 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:22 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:22 volumina volumio[1507]: return await handler(request) Dec 12 12:52:22 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:22 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:22 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:22 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:22 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:22 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:22 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:22 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:22 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:22 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:22 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:22 volumina volumio[1507]: return await handler(request) Dec 12 12:52:22 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:22 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:22 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:22 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:22 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:22 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:22 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:22 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:23 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:23 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:23 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:23 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:23 volumina volumio[1507]: return await handler(request) Dec 12 12:52:23 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:23 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:23 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:23 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:23 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:23 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:23 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:23 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:23 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:23 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:23 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:23 volumina volumio[1507]: return await handler(request) Dec 12 12:52:23 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:23 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:23 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:23 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:23 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:23 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:23 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:23 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:24 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:24 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:24 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:24 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:24 volumina volumio[1507]: return await handler(request) Dec 12 12:52:24 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:24 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:24 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:24 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:24 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:24 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:24 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:24 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:24 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:24 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:24 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:24 volumina volumio[1507]: return await handler(request) Dec 12 12:52:24 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:24 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:24 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:24 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:24 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:24 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:24 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:24 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:24 volumina go-librespot[1512]: time="2025-12-12T12:52:24+01:00" level=info msg="playback was transferred to Pixel 7" Dec 12 12:52:24 volumina go-librespot[1512]: time="2025-12-12T12:52:24+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 12 12:52:24 volumina go-librespot[1512]: time="2025-12-12T12:52:24+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 12 12:52:25 volumina go-librespot[1512]: time="2025-12-12T12:52:25+01:00" level=debug msg="put connect state inactive" Dec 12 12:52:25 volumina go-librespot[1512]: time="2025-12-12T12:52:25+01:00" level=trace msg="emitting websocket event: inactive" Dec 12 12:52:25 volumina go-librespot[1512]: time="2025-12-12T12:52:25+01:00" level=trace msg="emitting websocket event: stopped" Dec 12 12:52:25 volumina go-librespot[1512]: time="2025-12-12T12:52:25+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 457" Dec 12 12:52:25 volumina go-librespot[1512]: time="2025-12-12T12:52:25+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1350" Dec 12 12:52:25 volumina go-librespot[1512]: time="2025-12-12T12:52:25+01:00" level=debug msg="dealer connection closed" Dec 12 12:52:25 volumina go-librespot[1512]: time="2025-12-12T12:52:25+01:00" level=debug msg="dealer recv loop stopped" Dec 12 12:52:25 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:25 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:25 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:25 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:25 volumina volumio[1507]: return await handler(request) Dec 12 12:52:25 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:25 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:25 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:25 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:25 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:25 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:25 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:25 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:25 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:25 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:25 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:25 volumina volumio[1507]: return await handler(request) Dec 12 12:52:25 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:25 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:25 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:25 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:25 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:25 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:25 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:25 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:26 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:26 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:26 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:26 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:26 volumina volumio[1507]: return await handler(request) Dec 12 12:52:26 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:26 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:26 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:26 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:26 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:26 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:26 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:26 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:26 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:26 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:26 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:26 volumina volumio[1507]: return await handler(request) Dec 12 12:52:26 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:26 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:26 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:26 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:26 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:26 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:26 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:26 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:27 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:27 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:27 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:27 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:27 volumina volumio[1507]: return await handler(request) Dec 12 12:52:27 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:27 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:27 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:27 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:27 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:27 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:27 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:27 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:27 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:27 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:27 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:27 volumina volumio[1507]: return await handler(request) Dec 12 12:52:27 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:27 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:27 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:27 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:27 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:27 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:27 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:27 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="obtained new client token: AAAKikU/RApmCe+8B2lBZwf9c5wbxId1YN5w0+o4cf9clYpDRPQQb++V2s8eDoGrVRYrGwbP/WcWei3bDXlhMs2QOubnhuJ9YhUFzFbZVGUMbd2ipPW6L9BaKMNcN4/B3tq7aYXnfB0f5RVuSAoJIX9rwbF90ypZty5NHNtYqR1f9A4yAxgBY1SSJ+UbHQT5YDHjWjYXFL/G+hYV2UUFe2t9+3KpFH+93sxABx9+6QslKo2k531n3E7/" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="completed keyexchange" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="completed challenge" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=info msg="authenticated AP" username="hc*********************f3" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=info msg="authenticated Login5" username="hc*********************f3" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=info msg="accepted zeroconf from Pixel 7" username="hc*********************f3" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="dealer connection opened" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=trace msg="starting accesspoint recv loop" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=trace msg="starting dealer recv loop" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=trace msg="received accesspoint ping" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="received connection id: NmNjMGFkZTYtYmY3...QUVCQTY0NDIwNw==" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=trace msg="received accesspoint pong ack" Dec 12 12:52:28 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:28 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:28 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:28 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:28 volumina volumio[1507]: return await handler(request) Dec 12 12:52:28 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:28 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:28 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:28 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:28 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:28 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:28 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:28 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:28 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:28 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:28 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:28 volumina volumio[1507]: return await handler(request) Dec 12 12:52:28 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:28 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:28 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:28 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:28 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:28 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:28 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:28 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="update volume requested to 37084/65535" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="handling transfer player command from b159c3e88982f1bd7f65c945018339e7124c6d21" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="resolved context of track" uri="spotify:user:hc0ll5j475je7onbsnxz88xf3:collection" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=trace msg="fetched new page 0 with 396 items (list: 396)" uri="spotify:user:hc0ll5j475je7onbsnxz88xf3:collection" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="shuffled context with seed 11770175775317757038 (len: 396, keep: 220)" uri="spotify:user:hc0ll5j475je7onbsnxz88xf3:collection" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="loading track (paused: false, position: 4217ms)" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=trace msg="emitting websocket event: will_play" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1294" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="selected format OGG_VORBIS_320 (ca1ea43a2cba05127f86af2fbf5b86c0004fa808)" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=debug msg="requested aes key for file ca1ea43a2cba05127f86af2fbf5b86c0004fa808, gid: 7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:28 volumina go-librespot[1512]: time="2025-12-12T12:52:28+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="fetched first chunk of 17, total size is 8810688 bytes" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=trace msg="seek to 4217ms (diff: 112ms, samples: 185969, bytes: 118798)" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="created new output device" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=info msg="loaded track \"Shooting Stars\" (paused: false, position: 4217ms, duration: 227026ms, prefetched: false)" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=trace msg="scheduling prefetch in 193s" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=trace msg="emitting websocket event: metadata" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=trace msg="emitting websocket event: active" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="sending successful reply for dealer request" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=trace msg="emitting websocket event: playing" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=trace msg="emitting websocket event: volume" Dec 12 12:52:29 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:29 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:29 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:29 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:29 volumina volumio[1507]: return await handler(request) Dec 12 12:52:29 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:29 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:29 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:29 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:29 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:29 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:29 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:29 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:29 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:29 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:29 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:29 volumina volumio[1507]: return await handler(request) Dec 12 12:52:29 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:29 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:29 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:29 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:29 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:29 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:29 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:29 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:29 volumina go-librespot[1512]: time="2025-12-12T12:52:29+01:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:7tOcPDj3vyopZ404pY6UuP" Dec 12 12:52:30 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:30 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:30 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:30 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:30 volumina volumio[1507]: return await handler(request) Dec 12 12:52:30 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:30 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:30 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:30 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:30 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:30 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:30 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:30 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:30 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:30 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:30 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:30 volumina volumio[1507]: return await handler(request) Dec 12 12:52:30 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:30 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:30 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:30 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:30 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:30 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:30 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:30 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:31 volumina go-librespot[1512]: time="2025-12-12T12:52:31+01:00" level=debug msg="handling seek_to player command from b159c3e88982f1bd7f65c945018339e7124c6d21" Dec 12 12:52:31 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:31 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:31 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:31 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:31 volumina volumio[1507]: return await handler(request) Dec 12 12:52:31 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:31 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:31 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:31 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:31 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:31 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:31 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:31 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:31 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:31 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:31 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:31 volumina volumio[1507]: return await handler(request) Dec 12 12:52:31 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:31 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:31 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:31 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:31 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:31 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:31 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:31 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:32 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:32 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:32 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:32 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:32 volumina volumio[1507]: return await handler(request) Dec 12 12:52:32 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:32 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:32 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:32 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:32 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:32 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:32 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:32 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:32 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:32 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:32 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:32 volumina volumio[1507]: return await handler(request) Dec 12 12:52:32 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:32 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:32 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:32 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:32 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:32 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:32 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:32 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:33 volumina volumio[1145]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 12 12:52:33 volumina volumio[1145]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 12 12:52:33 volumina volumio[1145]: info: camilladsp stopping service pid 1487... Dec 12 12:52:33 volumina volumio[1145]: info: camilladsp service terminated, instance 1 Dec 12 12:52:33 volumina volumio[1145]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 12 12:52:33 volumina volumio[1145]: info: camilladsp service started and running in background, instance 1 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Dec 12 12:52:33 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 75 Dec 12 12:52:33 volumina volumio[1145]: info: VolumeController::SetAlsaVolume75 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":74,"max":100}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 74 Dec 12 12:52:33 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 74 Dec 12 12:52:33 volumina volumio[1145]: info: VolumeController::SetAlsaVolume74 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":71,"max":100}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 71 Dec 12 12:52:33 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 71 Dec 12 12:52:33 volumina volumio[1145]: info: VolumeController::SetAlsaVolume71 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":63,"max":100}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 63 Dec 12 12:52:33 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 63 Dec 12 12:52:33 volumina volumio[1145]: info: VolumeController::SetAlsaVolume63 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":59,"max":100}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 59 Dec 12 12:52:33 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 59 Dec 12 12:52:33 volumina volumio[1145]: info: VolumeController::SetAlsaVolume59 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":58,"max":100}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 58 Dec 12 12:52:33 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 58 Dec 12 12:52:33 volumina volumio[1145]: info: VolumeController::SetAlsaVolume58 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"volume","data":{"value":57,"max":100}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: RECEIVED SPOTIFY VOLUME 57 Dec 12 12:52:33 volumina volumio[1145]: info: Setting Volumio Volume from Spotify: 57 Dec 12 12:52:33 volumina volumio[1145]: info: VolumeController::SetAlsaVolume57 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"inactive","data":null} Dec 12 12:52:33 volumina volumio[1145]: error: Failed to decode event: inactive Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":""}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: PUSH STATE SPOTIFY Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 12 12:52:33 volumina volumio[1145]: info: CoreCommandRouter::servicePushState Dec 12 12:52:33 volumina volumio[1145]: info: CoreStateMachine::pushState Dec 12 12:52:33 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:52:33 volumina volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 12 12:52:33 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:52:33 volumina volumio[1145]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 12 12:52:33 volumina volumio[1145]: verbose: CURRENT POSITION 0 Dec 12 12:52:33 volumina volumio[1145]: info: CoreStateMachine::syncState stateService stop Dec 12 12:52:33 volumina volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 12 12:52:33 volumina volumio[1145]: info: CoreStateMachine::pushState Dec 12 12:52:33 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:52:33 volumina volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 12:52:33 volumina volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 12 12:52:33 volumina volumio[1145]: info: No code Dec 12 12:52:33 volumina volumio[1145]: info: CoreStateMachine::pushState Dec 12 12:52:33 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:52:33 volumina volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:user:hc0ll5j475je7onbsnxz88xf3:collection","uri":"spotify:track:7tOcPDj3vyopZ404pY6UuP","play_origin":"playlist"}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7tOcPDj3vyopZ404pY6UuP","name":"Shooting Stars","artist_names":["Bag Raiders"],"album_name":"Shooting Stars","album_cover_url":"https://i.scdn.co/image/ab67616d00001e025112a7bcda53119caff2775a","position":4217,"duration":227026,"release_date":"year:2009 month:1 day:1","track_number":1,"disc_number":1}} Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"active","data":null} Dec 12 12:52:33 volumina volumio[1145]: info: Aligning Spotify Volume to Volumio Volume Dec 12 12:52:33 volumina volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 12 12:52:33 volumina volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 12 12:52:33 volumina volumio[1145]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:user:hc0ll5j475je7onbsnxz88xf3:collection","uri":"spotify:track:7tOcPDj3vyopZ404pY6UuP","resume":false,"play_origin":"playlist"}} Dec 12 12:52:33 volumina volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 12:52:33 volumina volumio[1145]: TypeError: Cannot read properties of undefined (reading 'service') Dec 12 12:52:33 volumina volumio[1145]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 12 12:52:33 volumina volumio[1145]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Dec 12 12:52:33 volumina volumio[1145]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 12 12:52:33 volumina volumio[1145]: at WebSocket.emit (node:events:514:28) Dec 12 12:52:33 volumina volumio[1145]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 12 12:52:33 volumina volumio[1145]: at Receiver.emit (node:events:514:28) Dec 12 12:52:33 volumina volumio[1145]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 12 12:52:33 volumina volumio[1145]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 12 12:52:33 volumina volumio[1145]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 12 12:52:33 volumina volumio[1145]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 12 12:52:33 volumina volumio[1145]: at writeOrBuffer (node:internal/streams/writable:399:12) Dec 12 12:52:33 volumina volumio[1145]: at _write (node:internal/streams/writable:340:10) Dec 12 12:52:33 volumina volumio[1145]: at Writable.write (node:internal/streams/writable:344:10) Dec 12 12:52:33 volumina volumio[1145]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Dec 12 12:52:33 volumina volumio[1145]: at Socket.emit (node:events:514:28) Dec 12 12:52:33 volumina volumio[1145]: at addChunk (node:internal/streams/readable:343:12) Dec 12 12:52:33 volumina volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 12:52:33 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:33 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:33 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:33 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:33 volumina volumio[1507]: return await handler(request) Dec 12 12:52:33 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:33 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:33 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:33 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:33 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:33 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:33 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:33 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:33 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:33 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:33 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:33 volumina volumio[1507]: return await handler(request) Dec 12 12:52:33 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:33 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:33 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:33 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:33 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:33 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:33 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:33 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:34 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:34 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:34 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:34 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:34 volumina volumio[1507]: return await handler(request) Dec 12 12:52:34 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:34 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:34 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:34 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:34 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:34 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:34 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:34 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:34 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:34 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:34 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:34 volumina volumio[1507]: return await handler(request) Dec 12 12:52:34 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:34 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:34 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:34 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:34 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:34 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:34 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:34 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:35 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:35 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:35 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:35 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:35 volumina volumio[1507]: return await handler(request) Dec 12 12:52:35 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:35 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:35 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:35 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:35 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:35 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:35 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:35 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:35 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:35 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:35 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:35 volumina volumio[1507]: return await handler(request) Dec 12 12:52:35 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:35 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:35 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:35 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:35 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:35 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:35 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:35 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:36 volumina sudo[1837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 12:51' Dec 12 12:52:36 volumina sudo[1837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:52:36 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:36 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:36 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:36 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:36 volumina volumio[1507]: return await handler(request) Dec 12 12:52:36 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 164, in get_param Dec 12 12:52:36 volumina volumio[1507]: result = cdsp.volume.main_volume() Dec 12 12:52:36 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 39, in main_volume Dec 12 12:52:36 volumina volumio[1507]: vol = self.client.query("GetVolume") Dec 12 12:52:36 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:36 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:36 volumina volumio[1507]: OSError: Not connected to CamillaDSP Dec 12 12:52:36 volumina volumio[1507]: ERROR:aiohttp.server:Error handling request from 192.168.8.178 Dec 12 12:52:36 volumina volumio[1507]: Traceback (most recent call last): Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_protocol.py", line 480, in _handle_request Dec 12 12:52:36 volumina volumio[1507]: resp = await request_handler(request) Dec 12 12:52:36 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/aiohttp/web_app.py", line 569, in _handle Dec 12 12:52:36 volumina volumio[1507]: return await handler(request) Dec 12 12:52:36 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 166, in get_param Dec 12 12:52:36 volumina volumio[1507]: result = cdsp.volume.main_mute() Dec 12 12:52:36 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/volume.py", line 133, in main_mute Dec 12 12:52:36 volumina volumio[1507]: mute = self.client.query("GetMute") Dec 12 12:52:36 volumina volumio[1507]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Dec 12 12:52:36 volumina volumio[1507]: File "/data/plugins/audio_interface/fusiondsp/cgui/lib/python3.11/site-packages/camilladsp/camillaws.py", line 42, in query Dec 12 12:52:36 volumina volumio[1507]: raise IOError("Not connected to CamillaDSP") Dec 12 12:52:36 volumina volumio[1507]: OSError: Not connected to CamillaDSP PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"