-- Logs begin at Wed 2025-05-21 13:50:58 CEST, end at Wed 2025-05-21 13:53:46 CEST. -- May 21 13:52:00 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:00 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:00 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:00 volumio volumio[1324]: resp = await task May 21 13:52:00 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:00 volumio volumio[1324]: resp = await handler(request) May 21 13:52:00 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:00 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:00 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:00 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:00 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:00 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:00 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:00 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:00 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:00 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:00 volumio volumio[1324]: resp = await task May 21 13:52:00 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:00 volumio volumio[1324]: resp = await handler(request) May 21 13:52:00 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:00 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:00 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:00 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:00 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:00 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:00 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:00 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:00 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:00 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:00 volumio volumio[1324]: resp = await task May 21 13:52:00 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:00 volumio volumio[1324]: resp = await handler(request) May 21 13:52:00 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:00 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:00 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:00 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:00 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:00 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:00 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:00 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:00 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:00 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:00 volumio volumio[1324]: resp = await task May 21 13:52:00 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:00 volumio volumio[1324]: resp = await handler(request) May 21 13:52:00 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:00 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:00 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:00 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:00 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:00 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:00 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:00 volumio sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 13:52:00 volumio sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:52:00 volumio sudo[1443]: pam_unix(sudo:session): session closed for user root May 21 13:52:00 volumio sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 13:52:00 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:52:00 volumio sudo[1446]: pam_unix(sudo:session): session closed for user root May 21 13:52:00 volumio sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 21 13:52:00 volumio sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:52:01 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 21 13:52:01 volumio sudo[1450]: pam_unix(sudo:session): session closed for user root May 21 13:52:01 volumio volumio[1004]: info: Upmpdcli Daemon Started May 21 13:52:01 volumio volumio[1452]: Generating RSA private key, 4096 bit long modulus (2 primes) May 21 13:52:06 volumio volumio[1452]: .........................................................++++ May 21 13:52:07 volumio volumio[1452]: .............++++ May 21 13:52:07 volumio volumio[1452]: e is 65537 (0x010001) May 21 13:52:07 volumio volumio[1452]: writing RSA key May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 21 13:52:09 volumio volumio[1004]: info: Adding plugin bluetooth to MyMusic Plugins May 21 13:52:09 volumio volumio[1004]: info: Adding plugin multiroom to MyMusic Plugins May 21 13:52:09 volumio volumio[1004]: info: Adding plugin metavolumio to MyMusic Plugins May 21 13:52:09 volumio volumio[1004]: info: Adding plugin cd_controller to MyMusic Plugins May 21 13:52:09 volumio volumio[1004]: info: Adding plugin qobuzconnect to MyMusic Plugins May 21 13:52:09 volumio volumio[1004]: info: Adding plugin smart_inputs to MyMusic Plugins May 21 13:52:09 volumio volumio[1004]: info: Adding plugin tidalconnect to MyMusic Plugins May 21 13:52:09 volumio volumio[1004]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 21 13:52:10 volumio volumio[1004]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 21 13:52:10 volumio volumio[1004]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 21 13:52:10 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:10 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:10 volumio volumio[1004]: info: Starting MyVolumio Remote Streaming Endpoints May 21 13:52:10 volumio volumio[1004]: info: MyVolumio login type: Token May 21 13:52:10 volumio volumio[1004]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 21 13:52:10 volumio volumio[1004]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 21 13:52:11 volumio volumio[1004]: info: Starting Streaming Service Transparent Proxy May 21 13:52:11 volumio volumio[1004]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 21 13:52:11 volumio volumio[1004]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 21 13:52:11 volumio volumio[1004]: info: Streaming services startup May 21 13:52:11 volumio volumio[1004]: info: Starting Streaming Daemon May 21 13:52:11 volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 21 13:52:11 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:52:11 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root May 21 13:52:11 volumio volumio[1004]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 21 13:52:11 volumio volumio[1004]: error: Cannot start Volumio Streaming Daemon May 21 13:52:11 volumio volumio[1004]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 21 13:52:11 volumio volumio[1004]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 21 13:52:12 volumio volumio[1004]: STREAMING PROXY: Starting server on port 3245 May 21 13:52:12 volumio volumio[1004]: Node JS runtime: 14 May 21 13:52:12 volumio volumio[1004]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 21 13:52:12 volumio volumio[1004]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 21 13:52:12 volumio volumio[1004]: info: Preload queue cleared May 21 13:52:12 volumio volumio[1004]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::ClearQueue May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::stop May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::clearPlayQueue May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::saveQueue May 21 13:52:12 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::addQueueItems May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::addQueueItems May 21 13:52:12 volumio volumio[1004]: info: Preload queue cleared May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F01%20-%20Never%20Let%20Me%20Go.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac May 21 13:52:12 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::saveQueue May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock May 21 13:52:12 volumio volumio[1004]: info: CoreCommandRouter::volumioPlay May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::play index 0 May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::addQueueItems May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::addQueueItems May 21 13:52:12 volumio volumio[1004]: info: Preload queue cleared May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/02 - Prelude to a Kiss.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/02 - Prelude to a Kiss.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/03 - Speak Low.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/03 - Speak Low.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/04 - Angel Eyes.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/04 - Angel Eyes.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/05 - Midnight Sun.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/05 - Midnight Sun.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/06 - Caravan.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/06 - Caravan.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/07 - Lush Life.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/07 - Lush Life.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/08 - Satin Doll.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/08 - Satin Doll.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/09 - It's Only a Paper Moon.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/09 - It's Only a Paper Moon.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/10 - Lonely Town.flac May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/10 - Lonely Town.flac in service mpd May 21 13:52:12 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/Andy Bey - American Song.m3u May 21 13:52:12 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/Andy Bey - American Song.m3u in service mpd May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::stop May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::play index undefined May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F02%20-%20Prelude%20to%20a%20Kiss.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/02 - Prelude to a Kiss.flac May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F03%20-%20Speak%20Low.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/03 - Speak Low.flac May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F04%20-%20Angel%20Eyes.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/04 - Angel Eyes.flac May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F05%20-%20Midnight%20Sun.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/05 - Midnight Sun.flac May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F06%20-%20Caravan.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/06 - Caravan.flac May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F07%20-%20Lush%20Life.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/07 - Lush Life.flac May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F08%20-%20Satin%20Doll.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/08 - Satin Doll.flac May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F09%20-%20It's%20Only%20a%20Paper%20Moon.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/09 - It's Only a Paper Moon.flac May 21 13:52:12 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Andy%20Bey/American%20Song/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FAndy%20Bey%20-%20American%20Song%20(2004%2C%20Savoy%20Jazz)%2F10%20-%20Lonely%20Town.flac&metadata=false May 21 13:52:12 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/10 - Lonely Town.flac May 21 13:52:12 volumio volumio[1004]: info: sendMpdCommand stop took 12 milliseconds May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear May 21 13:52:12 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::saveQueue May 21 13:52:12 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock May 21 13:52:12 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock May 21 13:52:12 volumio volumio[1004]: info: May 21 13:52:12 volumio volumio[1004]: ---------------------------- MPD announces system playlist update May 21 13:52:12 volumio volumio[1004]: info: Ignoring MPD Status Update May 21 13:52:12 volumio volumio[1004]: info: sendMpdCommand clear took 41 milliseconds May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac" May 21 13:52:12 volumio volumio[1004]: error: updateQueue error: null May 21 13:52:12 volumio volumio[1004]: info: May 21 13:52:12 volumio volumio[1004]: ---------------------------- MPD announces system playlist update May 21 13:52:12 volumio volumio[1004]: info: Ignoring MPD Status Update May 21 13:52:12 volumio volumio[1004]: info: ------------------------------ 8ms May 21 13:52:12 volumio volumio[1004]: info: sendMpdCommand add "USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac" took 5 milliseconds May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play May 21 13:52:12 volumio volumio[1004]: info: ------------------------------ 5ms May 21 13:52:12 volumio volumio[1004]: info: sendMpdCommand play took 3 milliseconds May 21 13:52:12 volumio volumio[1004]: info: May 21 13:52:12 volumio volumio[1004]: ---------------------------- MPD announces state update: player May 21 13:52:12 volumio volumio[1004]: info: ControllerMpd::getState May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status May 21 13:52:12 volumio volumio[1004]: info: FusionDsp - ---- read samplerate, raw: May 21 13:52:12 volumio volumio[1004]: error: FusionDsp - invalid sample rate May 21 13:52:12 volumio volumio[1004]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 21 13:52:12 volumio volumio[1004]: info: FusionDsp - ---- read samplerate from file: 44100 May 21 13:52:12 volumio volumio[1004]: info: camilladsp stopping service pid 1316... May 21 13:52:12 volumio volumio[1004]: info: camilladsp service terminated, instance 1 May 21 13:52:12 volumio volumio[1004]: error: FusionDsp - File size not found in array! May 21 13:52:12 volumio volumio[1004]: error: FusionDsp - Error in eqv! Cannot split values! May 21 13:52:12 volumio volumio[1004]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} May 21 13:52:12 volumio volumio[1004]: info: May 21 13:52:12 volumio volumio[1004]: ---------------------------- MPD announces state update: player May 21 13:52:12 volumio volumio[1004]: info: sendMpdCommand status took 81 milliseconds May 21 13:52:12 volumio volumio[1004]: info: ControllerMpd::getState May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::parseState May 21 13:52:12 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:52:13 volumio volumio[1004]: info: sendMpdCommand status took 5 milliseconds May 21 13:52:13 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 13:52:13 volumio volumio[1004]: verbose: ControllerMpd::parseState May 21 13:52:13 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:52:13 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo May 21 13:52:13 volumio volumio[1004]: info: ControllerMpd::pushState May 21 13:52:13 volumio volumio[1004]: info: CoreCommandRouter::servicePushState May 21 13:52:13 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:13 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Never Let Me Go","artist":"Andy Bey","album":"American Song","uri":"USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac","trackType":"flac"} May 21 13:52:13 volumio volumio[1004]: verbose: CURRENT POSITION 0 May 21 13:52:13 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play May 21 13:52:13 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop May 21 13:52:13 volumio volumio[1004]: info: ------------------------------ 97ms May 21 13:52:13 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 13:52:13 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo May 21 13:52:13 volumio volumio[1004]: info: ControllerMpd::pushState May 21 13:52:13 volumio volumio[1004]: info: CoreCommandRouter::servicePushState May 21 13:52:13 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:13 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":332,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"463 Kbps","isStreaming":false,"title":"Never Let Me Go","artist":"Andy Bey","album":"American Song","uri":"USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac","trackType":"flac"} May 21 13:52:13 volumio volumio[1004]: verbose: CURRENT POSITION 0 May 21 13:52:13 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play May 21 13:52:13 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play May 21 13:52:13 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload May 21 13:52:13 volumio volumio[1004]: info: CoreStateMachine::pushState May 21 13:52:13 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:13 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:52:13 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState May 21 13:52:13 volumio volumio[1004]: info: CoreStateMachine::pushState May 21 13:52:13 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:13 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState May 21 13:52:13 volumio volumio[1004]: info: ------------------------------ 48ms May 21 13:52:13 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 13:52:13 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 13:52:13 volumio volumio[1004]: info: MyVolumio token set successfully May 21 13:52:13 volumio volumio[1004]: info: MYVOLUMIO: Adding device May 21 13:52:13 volumio volumio[1004]: info: MYVOLUMIO: Evaluating Server May 21 13:52:13 volumio volumio[1004]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 21 13:52:13 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:13 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 128, in _query May 21 13:52:13 volumio volumio[1324]: rawrepl = self._ws.recv() May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 362, in recv May 21 13:52:13 volumio volumio[1324]: opcode, data = self.recv_data() May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 385, in recv_data May 21 13:52:13 volumio volumio[1324]: opcode, frame = self.recv_data_frame(control_frame) May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 406, in recv_data_frame May 21 13:52:13 volumio volumio[1324]: frame = self.recv_frame() May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 445, in recv_frame May 21 13:52:13 volumio volumio[1324]: return self.frame_buffer.recv_frame() May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 338, in recv_frame May 21 13:52:13 volumio volumio[1324]: self.recv_header() May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 294, in recv_header May 21 13:52:13 volumio volumio[1324]: header = self.recv_strict(2) May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 373, in recv_strict May 21 13:52:13 volumio volumio[1324]: bytes_ = self.recv(min(16384, shortage)) May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 529, in _recv May 21 13:52:13 volumio volumio[1324]: return recv(self.sock, bufsize) May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/websocket/_socket.py", line 123, in recv May 21 13:52:13 volumio volumio[1324]: "Connection to remote host was lost.") May 21 13:52:13 volumio volumio[1324]: websocket._exceptions.WebSocketConnectionClosedException: Connection to remote host was lost. May 21 13:52:13 volumio volumio[1324]: During handling of the above exception, another exception occurred: May 21 13:52:13 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:13 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:13 volumio volumio[1324]: resp = await task May 21 13:52:13 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:13 volumio volumio[1324]: resp = await handler(request) May 21 13:52:13 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:13 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:13 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 131, in _query May 21 13:52:13 volumio volumio[1324]: raise IOError("Lost connection to CamillaDSP") May 21 13:52:13 volumio volumio[1324]: OSError: Lost connection to CamillaDSP May 21 13:52:13 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:13 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:13 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:13 volumio volumio[1324]: resp = await task May 21 13:52:13 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:13 volumio volumio[1324]: resp = await handler(request) May 21 13:52:13 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:13 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:13 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:13 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:13 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:13 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:14 volumio volumio[1004]: info: MyVolumio status changed May 21 13:52:14 volumio volumio[1004]: info: Streaming services startup May 21 13:52:14 volumio volumio[1004]: info: Starting Streaming Daemon May 21 13:52:14 volumio volumio[1004]: info: Removing browser output: myVolumio user plan is not superstar May 21 13:52:14 volumio volumio[1004]: info: Removing audio output: May 21 13:52:14 volumio volumio[1004]: info: Stoppping Tunnel 1 May 21 13:52:14 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 21 13:52:14 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:52:14 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root May 21 13:52:14 volumio sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 21 13:52:14 volumio sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 13:52:14 volumio sudo[1646]: pam_unix(sudo:session): session closed for user root May 21 13:52:14 volumio volumio[1004]: error: Cannot start Volumio Streaming Daemon May 21 13:52:14 volumio volumio[1004]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 21 13:52:14 volumio volumio[1004]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 21 13:52:14 volumio volumio[1004]: info: Remote SSH Stopped May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 13:52:14 volumio volumio[1004]: info: Setting Geolocation for MyVolumio to eu6 May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:14 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:14 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:14 volumio volumio[1324]: resp = await task May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:14 volumio volumio[1324]: resp = await handler(request) May 21 13:52:14 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:14 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:14 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:14 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:14 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:14 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:14 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:14 volumio volumio[1324]: resp = await task May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:14 volumio volumio[1324]: resp = await handler(request) May 21 13:52:14 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:14 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:14 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:14 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:14 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:14 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:14 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:14 volumio volumio[1324]: resp = await task May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:14 volumio volumio[1324]: resp = await handler(request) May 21 13:52:14 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:14 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:14 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:14 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:14 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:14 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:14 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:14 volumio volumio[1324]: resp = await task May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:14 volumio volumio[1324]: resp = await handler(request) May 21 13:52:14 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:14 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:14 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:14 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:14 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::volumioGetVisibleSources May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:52:14 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:14 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:14 volumio volumio[1324]: resp = await task May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:14 volumio volumio[1324]: resp = await handler(request) May 21 13:52:14 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:14 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:14 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:14 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:14 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:14 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 13:52:14 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:14 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:14 volumio volumio[1324]: resp = await task May 21 13:52:14 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:14 volumio volumio[1324]: resp = await handler(request) May 21 13:52:14 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:14 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:14 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:14 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:14 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:14 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 13:52:14 volumio volumio[1004]: info: Received Get System Info May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 13:52:14 volumio volumio[1004]: info: Discovery: Getting this device information May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:52:14 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:52:14 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:14 volumio volumio[1004]: info: Listing playlists May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 21 13:52:14 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 13:52:15 volumio volumio[1004]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 21 13:52:15 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:52:15 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:15 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:52:15 volumio volumio[1004]: info: FusionDsp - 44 May 21 13:52:15 volumio volumio[1004]: info: Updating MyVolumio device info May 21 13:52:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:15 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:52:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 13:52:15 volumio volumio[1004]: info: Received Get System Info May 21 13:52:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 13:52:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 13:52:15 volumio volumio[1004]: info: Discovery: Getting this device information May 21 13:52:15 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:52:15 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:15 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 13:52:15 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:15 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:15 volumio volumio[1324]: resp = await task May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:15 volumio volumio[1324]: resp = await handler(request) May 21 13:52:15 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:15 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:15 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:15 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:15 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:15 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:15 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:15 volumio volumio[1324]: resp = await task May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:15 volumio volumio[1324]: resp = await handler(request) May 21 13:52:15 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:15 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:15 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:15 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:15 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:15 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:15 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:15 volumio volumio[1324]: resp = await task May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:15 volumio volumio[1324]: resp = await handler(request) May 21 13:52:15 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:15 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:15 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:15 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:15 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:15 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:15 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:15 volumio volumio[1324]: resp = await task May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:15 volumio volumio[1324]: resp = await handler(request) May 21 13:52:15 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:15 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:15 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:15 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:15 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:15 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:15 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:15 volumio volumio[1324]: resp = await task May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:15 volumio volumio[1324]: resp = await handler(request) May 21 13:52:15 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:15 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:15 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:15 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:15 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:15 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:15 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:15 volumio volumio[1324]: resp = await task May 21 13:52:15 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:15 volumio volumio[1324]: resp = await handler(request) May 21 13:52:15 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:15 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:15 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:15 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:15 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:15 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:16 volumio volumio[1004]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 21 13:52:16 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 21 13:52:17 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:17 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:17 volumio volumio[1324]: resp = await task May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:17 volumio volumio[1324]: resp = await handler(request) May 21 13:52:17 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:17 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:17 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:17 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:17 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:17 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:17 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:17 volumio volumio[1324]: resp = await task May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:17 volumio volumio[1324]: resp = await handler(request) May 21 13:52:17 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:17 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:17 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:17 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:17 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:17 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:17 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:17 volumio volumio[1324]: resp = await task May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:17 volumio volumio[1324]: resp = await handler(request) May 21 13:52:17 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:17 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:17 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:17 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:17 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:17 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:17 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:17 volumio volumio[1324]: resp = await task May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:17 volumio volumio[1324]: resp = await handler(request) May 21 13:52:17 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:17 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:17 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:17 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:17 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:17 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:17 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:17 volumio volumio[1324]: resp = await task May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:17 volumio volumio[1324]: resp = await handler(request) May 21 13:52:17 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:17 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:17 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:17 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:17 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:17 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:17 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:17 volumio volumio[1324]: resp = await task May 21 13:52:17 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:17 volumio volumio[1324]: resp = await handler(request) May 21 13:52:17 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:17 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:17 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:17 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:17 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:17 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 13:52:17 volumio volumio[1004]: info: Received Get System Info May 21 13:52:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 13:52:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 13:52:17 volumio volumio[1004]: info: Discovery: Getting this device information May 21 13:52:17 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:52:17 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:17 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 13:52:18 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:18 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:18 volumio volumio[1324]: resp = await task May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:18 volumio volumio[1324]: resp = await handler(request) May 21 13:52:18 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:18 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:18 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:18 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:18 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:18 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:18 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:18 volumio volumio[1324]: resp = await task May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:18 volumio volumio[1324]: resp = await handler(request) May 21 13:52:18 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:18 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:18 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:18 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:18 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:18 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:18 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:18 volumio volumio[1324]: resp = await task May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:18 volumio volumio[1324]: resp = await handler(request) May 21 13:52:18 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:18 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:18 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:18 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:18 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:18 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:18 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:18 volumio volumio[1324]: resp = await task May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:18 volumio volumio[1324]: resp = await handler(request) May 21 13:52:18 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:18 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:18 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:18 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:18 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:18 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:18 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:18 volumio volumio[1324]: resp = await task May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:18 volumio volumio[1324]: resp = await handler(request) May 21 13:52:18 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:18 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:18 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:18 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:18 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:18 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:18 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:18 volumio volumio[1324]: resp = await task May 21 13:52:18 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:18 volumio volumio[1324]: resp = await handler(request) May 21 13:52:18 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:18 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:18 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:18 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:18 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:18 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:19 volumio systemd[1]: systemd-timedated.service: Succeeded. May 21 13:52:19 volumio volumio[1004]: info: MYVOLUMIO: Adding device May 21 13:52:19 volumio volumio[1004]: info: MYVOLUMIO: Evaluating Server May 21 13:52:19 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:19 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:19 volumio volumio[1324]: resp = await task May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:19 volumio volumio[1324]: resp = await handler(request) May 21 13:52:19 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:19 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:19 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:19 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:19 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:19 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:19 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:19 volumio volumio[1324]: resp = await task May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:19 volumio volumio[1324]: resp = await handler(request) May 21 13:52:19 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:19 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:19 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:19 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:19 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:19 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:19 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:19 volumio volumio[1324]: resp = await task May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:19 volumio volumio[1324]: resp = await handler(request) May 21 13:52:19 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:19 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:19 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:19 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:19 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:19 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:19 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:19 volumio volumio[1324]: resp = await task May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:19 volumio volumio[1324]: resp = await handler(request) May 21 13:52:19 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:19 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:19 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:19 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:19 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:19 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:19 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:19 volumio volumio[1324]: resp = await task May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:19 volumio volumio[1324]: resp = await handler(request) May 21 13:52:19 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:19 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:19 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:19 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:19 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:19 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:19 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:19 volumio volumio[1324]: resp = await task May 21 13:52:19 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:19 volumio volumio[1324]: resp = await handler(request) May 21 13:52:19 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:19 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:19 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:19 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:19 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:19 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:20 volumio volumio[1004]: info: Setting Geolocation for MyVolumio to eu6 May 21 13:52:20 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:20 volumio volumio[1004]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 21 13:52:20 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:20 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:20 volumio volumio[1324]: resp = await task May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:20 volumio volumio[1324]: resp = await handler(request) May 21 13:52:20 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:20 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:20 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:20 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:20 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:20 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:20 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:20 volumio volumio[1324]: resp = await task May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:20 volumio volumio[1324]: resp = await handler(request) May 21 13:52:20 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:20 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:20 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:20 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:20 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:20 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:20 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:20 volumio volumio[1324]: resp = await task May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:20 volumio volumio[1324]: resp = await handler(request) May 21 13:52:20 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:20 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:20 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:20 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:20 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:20 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:20 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:20 volumio volumio[1324]: resp = await task May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:20 volumio volumio[1324]: resp = await handler(request) May 21 13:52:20 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:20 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:20 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:20 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:20 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:20 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:20 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:20 volumio volumio[1324]: resp = await task May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:20 volumio volumio[1324]: resp = await handler(request) May 21 13:52:20 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 90, in get_param May 21 13:52:20 volumio volumio[1324]: result = cdsp.get_volume() May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 276, in get_volume May 21 13:52:20 volumio volumio[1324]: vol = self._query("GetVolume") May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:20 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:20 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:20 volumio volumio[1324]: ERROR:aiohttp.server:Error handling request May 21 13:52:20 volumio volumio[1324]: Traceback (most recent call last): May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_protocol.py", line 406, in start May 21 13:52:20 volumio volumio[1324]: resp = await task May 21 13:52:20 volumio volumio[1324]: File "/usr/lib/python3/dist-packages/aiohttp/web_app.py", line 435, in _handle May 21 13:52:20 volumio volumio[1324]: resp = await handler(request) May 21 13:52:20 volumio volumio[1324]: File "/data/plugins/audio_interface/fusiondsp/cgui/backend/views.py", line 92, in get_param May 21 13:52:20 volumio volumio[1324]: result = cdsp.get_mute() May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 289, in get_mute May 21 13:52:20 volumio volumio[1324]: mute = self._query("GetMute") May 21 13:52:20 volumio volumio[1324]: File "/usr/local/lib/python3.7/dist-packages/camilladsp/camilladsp.py", line 134, in _query May 21 13:52:20 volumio volumio[1324]: raise IOError("Not connected to CamillaDSP") May 21 13:52:20 volumio volumio[1324]: OSError: Not connected to CamillaDSP May 21 13:52:21 volumio volumio[1004]: info: Updating MyVolumio device info May 21 13:52:21 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:21 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:21 volumio volumio[1004]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 21 13:52:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 21 13:52:24 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 13:52:24 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:24 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 13:52:25 volumio volumio-remote-updater[646]: No test mode May 21 13:52:25 volumio volumio-remote-updater[646]: No alpha test mode May 21 13:52:25 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 21 13:52:25 volumio volumio[1004]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 21 13:52:25 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 21 13:52:25 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 13:52:25 volumio volumio[1004]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 21 13:52:25 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:52:25 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPause May 21 13:52:30 volumio volumio[1004]: info: CoreStateMachine::pause May 21 13:52:30 volumio volumio[1004]: info: CoreStateMachine::stPlaybackTimer May 21 13:52:30 volumio volumio[1004]: info: CoreStateMachine::servicePause May 21 13:52:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::servicePause May 21 13:52:30 volumio volumio[1004]: info: ControllerMpd::pause May 21 13:52:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand pause May 21 13:52:30 volumio volumio[1004]: info: sendMpdCommand pause took 2 milliseconds May 21 13:52:30 volumio volumio[1004]: info: May 21 13:52:30 volumio volumio[1004]: ---------------------------- MPD announces state update: player May 21 13:52:30 volumio volumio[1004]: info: ControllerMpd::getState May 21 13:52:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status May 21 13:52:30 volumio volumio[1004]: info: sendMpdCommand status took 1 milliseconds May 21 13:52:30 volumio volumio[1004]: verbose: ControllerMpd::parseState May 21 13:52:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:52:30 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 13:52:30 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo May 21 13:52:30 volumio volumio[1004]: info: ControllerMpd::pushState May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::servicePushState May 21 13:52:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:30 volumio volumio[1004]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":960,"duration":332,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"463 Kbps","isStreaming":false,"title":"Never Let Me Go","artist":"Andy Bey","album":"American Song","uri":"USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac","trackType":"flac"} May 21 13:52:30 volumio volumio[1004]: verbose: CURRENT POSITION 0 May 21 13:52:30 volumio volumio[1004]: info: CoreStateMachine::syncState stateService pause May 21 13:52:30 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus pause May 21 13:52:30 volumio volumio[1004]: info: CoreStateMachine::pushState May 21 13:52:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState May 21 13:52:30 volumio volumio[1004]: info: CoreStateMachine::stPlaybackTimer May 21 13:52:30 volumio volumio[1004]: info: ------------------------------ 16ms May 21 13:52:30 volumio volumio[1004]: info: touch_display: Setting screensaver timeout to 2000 seconds. May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetBrowseSources May 21 13:52:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 13:52:31 volumio volumio[1004]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 21 13:52:31 volumio volumio[1004]: info: Completed starting MyVolumio Plugin May 21 13:52:31 volumio volumio[1004]: [Metrics] CommandRouter: 43s 940.17ms May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::volumiosetStartupVolume May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::Close All Modals sent May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::Close All Modals sent May 21 13:52:31 volumio volumio[1004]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 21 13:52:31 volumio volumio[1004]: error: FusionDsp - ---- read samplerate skipped, rate is already updating; keeping 44100 May 21 13:52:31 volumio volumio[1004]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 21 13:52:31 volumio volumio[1004]: info: FusionDsp - ---- read samplerate from file: 44100 May 21 13:52:31 volumio volumio[1004]: error: FusionDsp - File size not found in array! May 21 13:52:31 volumio volumio[1004]: error: FusionDsp - Error in eqv! Cannot split values! May 21 13:52:31 volumio volumio[1004]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 13:52:31 volumio volumio[1004]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 21 13:52:31 volumio volumio[1004]: info: Received Get System Version May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 13:52:31 volumio volumio[1004]: info: Received Get System Info May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 13:52:31 volumio volumio[1004]: info: Discovery: Getting this device information May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:52:31 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:52:31 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 13:52:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 21 13:52:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 13:52:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 21 13:52:34 volumio ntpd[815]: Soliciting pool server 212.45.144.88 May 21 13:52:35 volumio ntpd[815]: Soliciting pool server 212.45.144.3 May 21 13:52:36 volumio ntpd[815]: Soliciting pool server 129.152.16.145 May 21 13:52:37 volumio ntpd[815]: Soliciting pool server 89.46.74.148 May 21 13:52:37 volumio ntpd[815]: Soliciting pool server 185.19.184.35 May 21 13:52:38 volumio ntpd[815]: Soliciting pool server 80.211.238.32 May 21 13:52:38 volumio volumio[1004]: info: BOOT COMPLETED May 21 13:52:38 volumio ntpd[815]: Soliciting pool server 162.159.200.123 May 21 13:52:38 volumio ntpd[815]: Soliciting pool server 93.44.243.48 May 21 13:52:38 volumio ntpd[815]: Soliciting pool server 151.22.209.90 May 21 13:52:39 volumio ntpd[815]: Soliciting pool server 93.94.88.50 May 21 13:52:39 volumio ntpd[815]: Soliciting pool server 95.110.254.234 May 21 13:52:40 volumio ntpd[815]: Soliciting pool server 85.199.214.99 May 21 13:52:40 volumio ntpd[815]: Soliciting pool server 37.247.53.178 May 21 13:52:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 21 13:52:41 volumio volumio[1004]: info: CURURI: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks May 21 13:52:41 volumio volumio[1004]: info: Preload queue cleared May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/02. Sophisticated Lady.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/03. Ill Wind.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/04. Today I Am A Man.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/05. My Love And I.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/06. Theme from Markham.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/07. Lets Call It A Day.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/08. Angel Face.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/09. A Love Like This.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/10. My Old Flame.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/11. Goodbye.flac May 21 13:52:41 volumio volumio[1004]: info: Preloading song: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/12. Wahoo.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F01.%20If%20Im%20Lucky.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/02. Sophisticated Lady.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F02.%20Sophisticated%20Lady.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/02. Sophisticated Lady.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/03. Ill Wind.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F03.%20Ill%20Wind.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/03. Ill Wind.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/04. Today I Am A Man.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F04.%20Today%20I%20Am%20A%20Man.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/04. Today I Am A Man.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/05. My Love And I.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F05.%20My%20Love%20And%20I.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/05. My Love And I.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/06. Theme from Markham.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F06.%20Theme%20from%20Markham.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/06. Theme from Markham.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/07. Lets Call It A Day.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F07.%20Lets%20Call%20It%20A%20Day.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/07. Lets Call It A Day.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/08. Angel Face.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F08.%20Angel%20Face.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/08. Angel Face.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/09. A Love Like This.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F09.%20A%20Love%20Like%20This.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/09. A Love Like This.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/10. My Old Flame.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F10.%20My%20Old%20Flame.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/10. My Old Flame.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/11. Goodbye.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F11.%20Goodbye.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/11. Goodbye.flac May 21 13:52:41 volumio volumio[1004]: info: Exploding uri music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/12. Wahoo.flac in service mpd May 21 13:52:41 volumio volumio[1004]: info: ALBUMART /albumart?cacheid=299&web=Charlie%20Haden%20Quartet%20West/Sophisticated%20Ladies/extralarge&path=%2Fmnt%2FUSB%2FElements%2FJAZZ%2FCharlie%20Haden%20Quartet%20West%20-%20Sophisticated%20Ladies%20(EmArcy.%202011)%2024-96%20hdtracks%2F12.%20Wahoo.flac&metadata=false May 21 13:52:41 volumio volumio[1004]: info: URI /mnt/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/12. Wahoo.flac May 21 13:52:41 volumio ntpd[815]: Soliciting pool server 93.94.88.51 May 21 13:52:41 volumio ntpd[815]: Soliciting pool server 2a00:6d41:10:1194::3 May 21 13:52:43 volumio ntpd[815]: receive: Unexpected origin timestamp 0xebd83d09.e02f3a82 does not match aorg 0000000000.00000000 from server@212.45.144.3 xmt 0xebd83d0b.29e85b3a May 21 13:52:43 volumio ntpd[815]: receive: Unexpected origin timestamp 0xebd83d09.e02e7901 does not match aorg 0000000000.00000000 from server@129.152.16.145 xmt 0xebd83d0b.2924f7c8 May 21 13:52:43 volumio ntpd[815]: receive: Unexpected origin timestamp 0xebd83d09.e02dac23 does not match aorg 0000000000.00000000 from server@80.211.238.32 xmt 0xebd83d0b.29ded1ec May 21 13:52:43 volumio ntpd[815]: receive: Unexpected origin timestamp 0xebd83d09.e02cd214 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xebd83d0b.2a613002 May 21 13:52:43 volumio ntpd[815]: receive: Unexpected origin timestamp 0xebd83d09.e02ab362 does not match aorg 0000000000.00000000 from server@85.199.214.99 xmt 0xebd83d0b.29abff3d May 21 13:52:48 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:52:48 volumio volumio[1004]: info: FusionDsp - 44 May 21 13:52:48 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:00 volumio volumio[1004]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] May 21 13:53:00 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset May 21 13:53:00 volumio volumio[1004]: info: FusionDsp - Value for usedpreset: May 21 13:53:00 volumio volumio[1004]: error: FusionDsp - Value reading JSON file: {"leftfilter":"sx-96k.wav","attenuationl":"3.80","attenuationr":"3.80","rightfilter":"ds-96k.wav","leftfilterlabel":"sx-96k.wav","filter_format":"undefined","mergedeq":"Eq1|Conv|L/data/INTERNAL/FusionDsp/filters/sx-96k.wav|3.80|Eq2|Conv|R/data/INTERNAL/FusionDsp/filters/ds-96k.wav|3.80|","state4preset":["None",false,false,50,0,0,0,"None",true,false,false,0,0,false]} May 21 13:53:00 volumio volumio[1004]: info: FusionDsp - value state4preset None,false,false,50,0,0,0,None,true,false,false,0,0,false May 21 13:53:01 volumio volumio[1004]: error: FusionDsp - File size not found in array! May 21 13:53:01 volumio volumio[1004]: error: FusionDsp - Error in eqv! Cannot split values! May 21 13:53:01 volumio volumio[1004]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} May 21 13:53:01 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:01 volumio volumio[1004]: info: CoreCommandRouter::Close All Modals sent May 21 13:53:01 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:01 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:13 volumio volumio[1004]: info: CoreCommandRouter::volumioPlay May 21 13:53:13 volumio volumio[1004]: info: CoreStateMachine::play index undefined May 21 13:53:13 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:53:13 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:13 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer May 21 13:53:13 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:13 volumio volumio[1004]: info: ControllerMpd::resume May 21 13:53:13 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play May 21 13:53:13 volumio volumio[1004]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 21 13:53:13 volumio volumio[1004]: info: FusionDsp - ---- read samplerate from file: 44100 May 21 13:53:13 volumio volumio[1004]: error: FusionDsp - File size not found in array! May 21 13:53:13 volumio volumio[1004]: error: FusionDsp - Error in eqv! Cannot split values! May 21 13:53:13 volumio volumio[1004]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} May 21 13:53:13 volumio volumio[1004]: info: May 21 13:53:13 volumio volumio[1004]: ---------------------------- MPD announces state update: player May 21 13:53:13 volumio volumio[1004]: info: sendMpdCommand play took 17 milliseconds May 21 13:53:13 volumio volumio[1004]: info: ControllerMpd::getState May 21 13:53:13 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status May 21 13:53:13 volumio volumio[1004]: info: sendMpdCommand status took 1 milliseconds May 21 13:53:13 volumio volumio[1004]: verbose: ControllerMpd::parseState May 21 13:53:13 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:53:13 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 13:53:13 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo May 21 13:53:13 volumio volumio[1004]: info: ControllerMpd::pushState May 21 13:53:13 volumio volumio[1004]: info: CoreCommandRouter::servicePushState May 21 13:53:13 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:13 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1441,"duration":332,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"473 Kbps","isStreaming":false,"title":"Never Let Me Go","artist":"Andy Bey","album":"American Song","uri":"USB/Elements/JAZZ/Andy Bey - American Song (2004, Savoy Jazz)/01 - Never Let Me Go.flac","trackType":"flac"} May 21 13:53:13 volumio volumio[1004]: verbose: CURRENT POSITION 0 May 21 13:53:13 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play May 21 13:53:13 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus pause May 21 13:53:13 volumio volumio[1004]: info: CoreStateMachine::pushState May 21 13:53:13 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:13 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:53:13 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState May 21 13:53:13 volumio volumio[1004]: info: ------------------------------ 15ms May 21 13:53:21 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:53:21 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:23 volumio volumio[1004]: info: Preload queue cleared May 21 13:53:23 volumio volumio[1004]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::ClearQueue May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::stop May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::stPlaybackTimer May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::pushState May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:23 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:53:23 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::serviceStop May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:23 volumio volumio[1004]: info: CoreCommandRouter::serviceStop May 21 13:53:23 volumio volumio[1004]: info: ControllerMpd::stop May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::clearPlayQueue May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::saveQueue May 21 13:53:23 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::addQueueItems May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::addQueueItems May 21 13:53:23 volumio volumio[1004]: info: Preload queue cleared May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac May 21 13:53:23 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::saveQueue May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock May 21 13:53:23 volumio volumio[1004]: info: CoreCommandRouter::volumioPlay May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::play index 0 May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::addQueueItems May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::addQueueItems May 21 13:53:23 volumio volumio[1004]: info: Preload queue cleared May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/02. Sophisticated Lady.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/02. Sophisticated Lady.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/03. Ill Wind.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/03. Ill Wind.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/04. Today I Am A Man.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/04. Today I Am A Man.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/05. My Love And I.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/05. My Love And I.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/06. Theme from Markham.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/06. Theme from Markham.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/07. Lets Call It A Day.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/07. Lets Call It A Day.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/08. Angel Face.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/08. Angel Face.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/09. A Love Like This.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/09. A Love Like This.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/10. My Old Flame.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/10. My Old Flame.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/11. Goodbye.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/11. Goodbye.flac May 21 13:53:23 volumio volumio[1004]: info: Adding Item to queue: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/12. Wahoo.flac May 21 13:53:23 volumio volumio[1004]: info: Using cached record of: music-library/USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/12. Wahoo.flac May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::stop May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:53:23 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::saveQueue May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::play index undefined May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:23 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer May 21 13:53:23 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop May 21 13:53:23 volumio volumio[1004]: info: May 21 13:53:23 volumio volumio[1004]: ---------------------------- MPD announces state update: player May 21 13:53:23 volumio volumio[1004]: info: sendMpdCommand stop took 56 milliseconds May 21 13:53:23 volumio volumio[1004]: info: ControllerMpd::getState May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status May 21 13:53:23 volumio volumio[1004]: info: sendMpdCommand stop took 13 milliseconds May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear May 21 13:53:23 volumio volumio[1004]: info: May 21 13:53:23 volumio volumio[1004]: ---------------------------- MPD announces system playlist update May 21 13:53:23 volumio volumio[1004]: info: Ignoring MPD Status Update May 21 13:53:23 volumio volumio[1004]: info: sendMpdCommand status took 24 milliseconds May 21 13:53:23 volumio volumio[1004]: info: sendMpdCommand clear took 23 milliseconds May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::parseState May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac" May 21 13:53:23 volumio volumio[1004]: error: updateQueue error: null May 21 13:53:23 volumio volumio[1004]: info: May 21 13:53:23 volumio volumio[1004]: ---------------------------- MPD announces system playlist update May 21 13:53:23 volumio volumio[1004]: info: Ignoring MPD Status Update May 21 13:53:23 volumio volumio[1004]: info: ------------------------------ 6ms May 21 13:53:23 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 4 milliseconds May 21 13:53:23 volumio volumio[1004]: info: sendMpdCommand add "USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac" took 4 milliseconds May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo May 21 13:53:23 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play May 21 13:53:23 volumio volumio[1004]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 13:53:23 volumio volumio[1004]: info: ------------------------------ 33ms May 21 13:53:23 volumio volumio[1004]: info: touch_display: Setting screensaver timeout to 2000 seconds. May 21 13:53:23 volumio volumio[1004]: info: ------------------------------ 6ms May 21 13:53:23 volumio volumio[1004]: info: sendMpdCommand play took 4 milliseconds May 21 13:53:24 volumio volumio[1004]: info: May 21 13:53:24 volumio volumio[1004]: ---------------------------- MPD announces state update: player May 21 13:53:24 volumio volumio[1004]: info: ControllerMpd::getState May 21 13:53:24 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status May 21 13:53:24 volumio volumio[1004]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 21 13:53:24 volumio volumio[1004]: info: FusionDsp - ---- read samplerate from file: 96000 May 21 13:53:24 volumio volumio[1004]: info: camilladsp service terminated, instance 1 May 21 13:53:24 volumio volumio[1004]: error: FusionDsp - File size not found in array! May 21 13:53:24 volumio volumio[1004]: error: FusionDsp - Error in eqv! Cannot split values! May 21 13:53:24 volumio volumio[1004]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} May 21 13:53:24 volumio volumio[1004]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 May 21 13:53:24 volumio volumio[1004]: error: FusionDsp - ---- read samplerate skipped, rate is already updating; keeping 96000 May 21 13:53:24 volumio volumio[1004]: info: May 21 13:53:24 volumio volumio[1004]: ---------------------------- MPD announces state update: player May 21 13:53:24 volumio volumio[1004]: info: sendMpdCommand status took 18 milliseconds May 21 13:53:24 volumio volumio[1004]: info: ControllerMpd::getState May 21 13:53:24 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status May 21 13:53:24 volumio volumio[1004]: verbose: ControllerMpd::parseState May 21 13:53:24 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:53:24 volumio volumio[1004]: info: sendMpdCommand status took 1 milliseconds May 21 13:53:24 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 13:53:24 volumio volumio[1004]: verbose: ControllerMpd::parseState May 21 13:53:24 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:53:24 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo May 21 13:53:24 volumio volumio[1004]: info: ControllerMpd::pushState May 21 13:53:24 volumio volumio[1004]: info: CoreCommandRouter::servicePushState May 21 13:53:24 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:24 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":340,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"If I'm Lucky (feat. Melody Gardot)","artist":"Charlie Haden Quartet West","album":"Sophisticated Ladies","uri":"USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac","trackType":"flac"} May 21 13:53:24 volumio volumio[1004]: verbose: CURRENT POSITION 0 May 21 13:53:24 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play May 21 13:53:24 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop May 21 13:53:24 volumio volumio[1004]: info: ------------------------------ 25ms May 21 13:53:24 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 13:53:24 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo May 21 13:53:24 volumio volumio[1004]: info: ControllerMpd::pushState May 21 13:53:24 volumio volumio[1004]: info: CoreCommandRouter::servicePushState May 21 13:53:24 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:24 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":340,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2185 Kbps","isStreaming":false,"title":"If I'm Lucky (feat. Melody Gardot)","artist":"Charlie Haden Quartet West","album":"Sophisticated Ladies","uri":"USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac","trackType":"flac"} May 21 13:53:24 volumio volumio[1004]: verbose: CURRENT POSITION 0 May 21 13:53:24 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play May 21 13:53:24 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play May 21 13:53:24 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload May 21 13:53:24 volumio volumio[1004]: info: CoreStateMachine::pushState May 21 13:53:24 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:24 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:53:24 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState May 21 13:53:24 volumio volumio[1004]: info: CoreStateMachine::pushState May 21 13:53:24 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:24 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState May 21 13:53:24 volumio volumio[1004]: info: ------------------------------ 32ms May 21 13:53:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPause May 21 13:53:33 volumio volumio[1004]: info: CoreStateMachine::pause May 21 13:53:33 volumio volumio[1004]: info: CoreStateMachine::stPlaybackTimer May 21 13:53:33 volumio volumio[1004]: info: CoreStateMachine::servicePause May 21 13:53:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:33 volumio volumio[1004]: info: CoreCommandRouter::servicePause May 21 13:53:33 volumio volumio[1004]: info: ControllerMpd::pause May 21 13:53:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand pause May 21 13:53:33 volumio volumio[1004]: info: sendMpdCommand pause took 1 milliseconds May 21 13:53:33 volumio volumio[1004]: info: May 21 13:53:33 volumio volumio[1004]: ---------------------------- MPD announces state update: player May 21 13:53:33 volumio volumio[1004]: info: ControllerMpd::getState May 21 13:53:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status May 21 13:53:33 volumio volumio[1004]: info: sendMpdCommand status took 1 milliseconds May 21 13:53:33 volumio volumio[1004]: verbose: ControllerMpd::parseState May 21 13:53:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 13:53:33 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 13:53:33 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo May 21 13:53:33 volumio volumio[1004]: info: ControllerMpd::pushState May 21 13:53:33 volumio volumio[1004]: info: CoreCommandRouter::servicePushState May 21 13:53:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:33 volumio volumio[1004]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":493,"duration":340,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2185 Kbps","isStreaming":false,"title":"If I'm Lucky (feat. Melody Gardot)","artist":"Charlie Haden Quartet West","album":"Sophisticated Ladies","uri":"USB/Elements/JAZZ/Charlie Haden Quartet West - Sophisticated Ladies (EmArcy. 2011) 24-96 hdtracks/01. If Im Lucky.flac","trackType":"flac"} May 21 13:53:33 volumio volumio[1004]: verbose: CURRENT POSITION 0 May 21 13:53:33 volumio volumio[1004]: info: CoreStateMachine::syncState stateService pause May 21 13:53:33 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus pause May 21 13:53:33 volumio volumio[1004]: info: CoreStateMachine::pushState May 21 13:53:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:33 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 13:53:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState May 21 13:53:33 volumio volumio[1004]: info: CoreStateMachine::stPlaybackTimer May 21 13:53:33 volumio volumio[1004]: info: ------------------------------ 16ms May 21 13:53:33 volumio volumio[1004]: info: touch_display: Setting screensaver timeout to 2000 seconds. May 21 13:53:36 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState May 21 13:53:36 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 May 21 13:53:40 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 13:53:40 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 21 13:53:40 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:40 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:40 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:40 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:40 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:40 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:40 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:40 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:41 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:44 volumio volumio[1004]: info: FusionDsp - Stopping FusionDsp service May 21 13:53:44 volumio volumio[1004]: info: camilladsp service terminated, instance 1 May 21 13:53:44 volumio volumio[1004]: info: Disabling plugin fusiondsp May 21 13:53:44 volumio volumio[1004]: info: Preparing to generate the ALSA configuration file May 21 13:53:44 volumio sudo[2164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 21 13:53:44 volumio sudo[2164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:44 volumio volumio[1004]: info: Asound.conf file written May 21 13:53:44 volumio systemd[1]: Stopping FusionDsp Daemon... May 21 13:53:44 volumio volumio[1324]: Backend configuration: May 21 13:53:44 volumio volumio[1324]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 21 13:53:44 volumio volumio[1324]: active_config_txt: null May 21 13:53:44 volumio volumio[1324]: camilla_host: 127.0.0.1 May 21 13:53:44 volumio volumio[1324]: camilla_port: 9876 May 21 13:53:44 volumio volumio[1324]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 21 13:53:44 volumio volumio[1324]: config_dir: /data/configuration/audio_interface/fusiondsp May 21 13:53:44 volumio volumio[1324]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 21 13:53:44 volumio volumio[1324]: log_file: /tmp/camilladsp.log May 21 13:53:44 volumio volumio[1324]: on_get_active_config: null May 21 13:53:44 volumio volumio[1324]: on_set_active_config: null May 21 13:53:44 volumio volumio[1324]: port: 5011 May 21 13:53:44 volumio volumio[1324]: supported_capture_types: null May 21 13:53:44 volumio volumio[1324]: supported_playback_types: null May 21 13:53:44 volumio volumio[1324]: update_config_symlink: false May 21 13:53:44 volumio volumio[1324]: update_config_txt: false May 21 13:53:44 volumio volumio[1324]: ======== Running on http://0.0.0.0:5011 ======== May 21 13:53:44 volumio volumio[1324]: (Press CTRL+C to quit) May 21 13:53:44 volumio sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 21 13:53:44 volumio sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:44 volumio sudo[2167]: pam_unix(sudo:session): session closed for user root May 21 13:53:44 volumio volumio[1004]: info: Output device has changed, restarting MPD May 21 13:53:44 volumio systemd[1]: fusiondsp.service: Succeeded. May 21 13:53:44 volumio systemd[1]: Stopped FusionDsp Daemon. May 21 13:53:44 volumio sudo[2164]: pam_unix(sudo:session): session closed for user root May 21 13:53:44 volumio sudo[2172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 13:53:44 volumio sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:44 volumio volumio[1004]: info: Output device has changed, restarting Shairport Sync May 21 13:53:44 volumio sudo[2172]: pam_unix(sudo:session): session closed for user root May 21 13:53:44 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:44 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:53:44 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 13:53:44 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:44 volumio systemd[1]: Stopping Music Player Daemon... May 21 13:53:45 volumio volumio[1004]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 13:53:45 volumio volumio[1004]: info: Done. May 21 13:53:45 volumio volumio[1004]: info: MPD Permissions set May 21 13:53:45 volumio volumio[1004]: info: FusionDsp - Reporting Fusion DSP Disabled May 21 13:53:45 volumio volumio[1004]: info: Removing fusiondspeq DSP Signal Path Element May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio systemd[1]: mpd.service: Succeeded. May 21 13:53:45 volumio systemd[1]: Stopped Music Player Daemon. May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: Starting Shairport Sync May 21 13:53:45 volumio sudo[2186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 13:53:45 volumio sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio systemd[1]: Starting Music Player Daemon... May 21 13:53:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 13:53:45 volumio systemd[1]: shairport-sync.service: Succeeded. May 21 13:53:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 13:53:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 13:53:45 volumio sudo[2186]: pam_unix(sudo:session): session closed for user root May 21 13:53:45 volumio volumio[1004]: info: Shairport-Sync Started May 21 13:53:45 volumio sudo[2181]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 13:53:45 volumio sudo[2181]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio sudo[2181]: pam_unix(sudo:session): session closed for user root May 21 13:53:45 volumio volumio[1004]: info: Enabling plugin fusiondsp May 21 13:53:45 volumio volumio[1004]: info: Loading plugin "fusiondsp"... May 21 13:53:45 volumio volumio[1004]: info: Applying required configuration parameters for plugin fusiondsp May 21 13:53:45 volumio volumio[1004]: info: Preparing to generate the ALSA configuration file May 21 13:53:45 volumio volumio[1004]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 21 13:53:45 volumio volumio[1004]: info: Reading ALSA contributions from plugins. May 21 13:53:45 volumio volumio[1004]: info: Asound.conf file written May 21 13:53:45 volumio sudo[2195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 21 13:53:45 volumio sudo[2195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio sudo[2195]: pam_unix(sudo:session): session closed for user root May 21 13:53:45 volumio volumio[1004]: info: Output device has changed, restarting MPD May 21 13:53:45 volumio sudo[2200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 13:53:45 volumio sudo[2200]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio sudo[2200]: pam_unix(sudo:session): session closed for user root May 21 13:53:45 volumio volumio[1004]: info: Output device has changed, restarting Shairport Sync May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:53:45 volumio sudo[2203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 13:53:45 volumio sudo[2203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio volumio[1004]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 13:53:45 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 13:53:45 volumio systemd[1]: mpd.service: Succeeded. May 21 13:53:45 volumio systemd[1]: Stopped Music Player Daemon. May 21 13:53:45 volumio volumio[1004]: info: PLUGIN START: fusiondsp May 21 13:53:45 volumio volumio[1004]: info: Loading i18n strings for locale it May 21 13:53:45 volumio volumio[1004]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: FusionDsp - mixtype--------------------- None May 21 13:53:45 volumio volumio[1004]: info: Preparing to generate the ALSA configuration file May 21 13:53:45 volumio volumio[1004]: info: Done. May 21 13:53:45 volumio volumio[1004]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 21 13:53:45 volumio volumio[1004]: info: Reading ALSA contributions from plugins. May 21 13:53:45 volumio volumio[1004]: info: MPD Permissions set May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:45 volumio systemd[1]: Starting Music Player Daemon... May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 13:53:45 volumio volumio[1004]: info: Starting Shairport Sync May 21 13:53:45 volumio volumio[1004]: info: Asound.conf file unchanged, so no further update is needed May 21 13:53:45 volumio volumio[1004]: info: Output device has changed, restarting MPD May 21 13:53:45 volumio sudo[2214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 13:53:45 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 13:53:45 volumio systemd[1]: shairport-sync.service: Succeeded. May 21 13:53:45 volumio sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 13:53:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 13:53:45 volumio sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 13:53:45 volumio sudo[2208]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 13:53:45 volumio sudo[2208]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio volumio[1004]: info: Output device has changed, restarting Shairport Sync May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 13:53:45 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 13:53:45 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root May 21 13:53:45 volumio sudo[2217]: pam_unix(sudo:session): session closed for user root May 21 13:53:45 volumio sudo[2208]: pam_unix(sudo:session): session closed for user root May 21 13:53:45 volumio sudo[2221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 13:53:45 volumio sudo[2221]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 13:53:45 volumio systemd[1]: mpd.service: Succeeded. May 21 13:53:45 volumio volumio[1004]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 13:53:45 volumio systemd[1]: Stopped Music Player Daemon. May 21 13:53:45 volumio systemd[1]: Starting Music Player Daemon... May 21 13:53:45 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:45 volumio sudo[2230]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 13:53:45 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 13:53:45 volumio sudo[2230]: pam_unix(sudo:session): session closed for user root May 21 13:53:45 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:45 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 96 May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:46 volumio volumio[1004]: info: FusionDsp - 176.json,192.json,44.json,48.json,88.json,96.json,kef.json,test.json May 21 13:53:46 volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 13:53:46 volumio volumio[1004]: TypeError: Cannot read property 'off' of undefined May 21 13:53:46 volumio volumio[1004]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) May 21 13:53:46 volumio volumio[1004]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 21 13:53:46 volumio volumio[1004]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) May 21 13:53:46 volumio volumio[1004]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) May 21 13:53:46 volumio volumio[1004]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) May 21 13:53:46 volumio volumio[1004]: at Socket.emit (events.js:315:20) May 21 13:53:46 volumio volumio[1004]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 21 13:53:46 volumio volumio[1004]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 21 13:53:46 volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 13:53:46 volumio mpd[2232]: May 21 13:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 13:53:46 volumio sudo[2242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 13:52 May 21 13:53:46 volumio sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"