-- Logs begin at Mon 2025-09-15 17:47:57 GMT, end at Mon 2025-09-15 17:50:21 GMT. -- Sep 15 17:49:00 volumio volumio[1099]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 15 17:49:00 volumio volumio[1099]: info: Completed starting MyVolumio Plugin Sep 15 17:49:00 volumio volumio[1099]: [Metrics] CommandRouter: 45s 500.92ms Sep 15 17:49:00 volumio volumio[1099]: info: CoreCommandRouter::volumiosetStartupVolume Sep 15 17:49:00 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 17:49:00 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 17:49:00 volumio volumio[1099]: info: CoreCommandRouter::Close All Modals sent Sep 15 17:49:00 volumio volumio[1099]: info: CoreCommandRouter::Close All Modals sent Sep 15 17:49:00 volumio volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 17:49:00 volumio volumio[1099]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 17:49:00 volumio volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 17:49:00 volumio volumio[1099]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 17:49:01 volumio volumio[1099]: info: Received Get System Info Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:49:01 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 17:49:01 volumio volumio[1099]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 15 17:49:01 volumio volumio[1099]: info: Received Get System Version Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 17:49:01 volumio volumio[1099]: info: Received Get System Info Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:49:01 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:49:01 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 14. Sep 15 17:49:01 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:01 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:01 volumio volumio[2225]: Traceback (most recent call last): Sep 15 17:49:01 volumio volumio[2225]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:01 volumio volumio[2225]: from aiohttp import web Sep 15 17:49:01 volumio volumio[2225]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:49:01 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 15 17:49:01 volumio volumio[1099]: info: CoreCommandRouter::volumioGetQueue Sep 15 17:49:01 volumio volumio[1099]: info: CoreStateMachine::getQueue Sep 15 17:49:01 volumio volumio[1099]: info: CorePlayQueue::getQueue Sep 15 17:49:02 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 17:49:02 volumio volumio[1099]: info: Received Get System Info Sep 15 17:49:02 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:49:02 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:49:02 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:49:02 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:49:02 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:49:03 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 15. Sep 15 17:49:03 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:03 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:03 volumio volumio[2232]: Traceback (most recent call last): Sep 15 17:49:03 volumio volumio[2232]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:03 volumio volumio[2232]: from aiohttp import web Sep 15 17:49:03 volumio volumio[2232]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:03 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:03 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:05 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 16. Sep 15 17:49:05 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:05 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:05 volumio volumio[2281]: Traceback (most recent call last): Sep 15 17:49:05 volumio volumio[2281]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:05 volumio volumio[2281]: from aiohttp import web Sep 15 17:49:05 volumio volumio[2281]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:07 volumio volumio[1099]: info: BOOT COMPLETED Sep 15 17:49:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 17. Sep 15 17:49:08 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:08 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:08 volumio volumio[2288]: Traceback (most recent call last): Sep 15 17:49:08 volumio volumio[2288]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:08 volumio volumio[2288]: from aiohttp import web Sep 15 17:49:08 volumio volumio[2288]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:09 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 15 17:49:10 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 18. Sep 15 17:49:10 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:10 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:10 volumio volumio[2293]: Traceback (most recent call last): Sep 15 17:49:10 volumio volumio[2293]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:10 volumio volumio[2293]: from aiohttp import web Sep 15 17:49:10 volumio volumio[2293]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:12 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 19. Sep 15 17:49:12 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:12 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:12 volumio volumio[2315]: Traceback (most recent call last): Sep 15 17:49:12 volumio volumio[2315]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:12 volumio volumio[2315]: from aiohttp import web Sep 15 17:49:12 volumio volumio[2315]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:14 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:14 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 20. Sep 15 17:49:14 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:14 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:14 volumio volumio[2320]: Traceback (most recent call last): Sep 15 17:49:14 volumio volumio[2320]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:14 volumio volumio[2320]: from aiohttp import web Sep 15 17:49:14 volumio volumio[2320]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:16 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 17:49:16 volumio volumio[1099]: info: Preload queue cleared Sep 15 17:49:17 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:17 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 21. Sep 15 17:49:17 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:17 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:17 volumio volumio[2327]: Traceback (most recent call last): Sep 15 17:49:17 volumio volumio[2327]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:17 volumio volumio[2327]: from aiohttp import web Sep 15 17:49:17 volumio volumio[2327]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:17 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 17:49:17 volumio volumio[1099]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 15 17:49:17 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 15 17:49:17 volumio volumio[1099]: info: Received Get System Version Sep 15 17:49:17 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 17:49:17 volumio volumio[1099]: info: Received Get System Info Sep 15 17:49:17 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:49:17 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:49:17 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:49:17 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:49:17 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioPause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::servicePause Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::servicePause Sep 15 17:49:18 volumio volumio[1099]: info: [1757958558320] ControllerUPNPBrowser::pause Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand pause Sep 15 17:49:18 volumio volumio[1099]: info: Sep 15 17:49:18 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand pause took 2 milliseconds Sep 15 17:49:18 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:18 volumio volumio[1099]: info: Sep 15 17:49:18 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:18 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:18 volumio volumio[1099]: info: Sep 15 17:49:18 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:18 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:18 volumio volumio[1099]: info: Sep 15 17:49:18 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:18 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand status took 5 milliseconds Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand status took 4 milliseconds Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand status took 2 milliseconds Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand status took 1 milliseconds Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 17:49:18 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:18 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:18 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:18 volumio volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":149353,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wild Heart","artist":"Luba","album":"All Or Nothing","uri":"http://192.168.1.30:9790/minimserver/*/Musik/Music/1980s*20albums/1980s*20albums/01*20-*20Wild*20Heart.mp3","trackType":"mp3"} Sep 15 17:49:18 volumio volumio[1099]: verbose: CURRENT POSITION 0 Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::syncState stateService pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Sep 15 17:49:18 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:18 volumio volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":149353,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wild Heart","artist":"Luba","album":"All Or Nothing","uri":"http://192.168.1.30:9790/minimserver/*/Musik/Music/1980s*20albums/1980s*20albums/01*20-*20Wild*20Heart.mp3","trackType":"mp3"} Sep 15 17:49:18 volumio volumio[1099]: verbose: CURRENT POSITION 0 Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::syncState stateService pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Sep 15 17:49:18 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:18 volumio volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":149353,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wild Heart","artist":"Luba","album":"All Or Nothing","uri":"http://192.168.1.30:9790/minimserver/*/Musik/Music/1980s*20albums/1980s*20albums/01*20-*20Wild*20Heart.mp3","trackType":"mp3"} Sep 15 17:49:18 volumio volumio[1099]: verbose: CURRENT POSITION 0 Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::syncState stateService pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Sep 15 17:49:18 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:18 volumio volumio[1099]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":149353,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wild Heart","artist":"Luba","album":"All Or Nothing","uri":"http://192.168.1.30:9790/minimserver/*/Musik/Music/1980s*20albums/1980s*20albums/01*20-*20Wild*20Heart.mp3","trackType":"mp3"} Sep 15 17:49:18 volumio volumio[1099]: verbose: CURRENT POSITION 0 Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::syncState stateService pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus pause Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Sep 15 17:49:18 volumio volumio[1099]: info: ------------------------------ 73ms Sep 15 17:49:18 volumio volumio[1099]: info: ------------------------------ 72ms Sep 15 17:49:18 volumio volumio[1099]: info: ------------------------------ 71ms Sep 15 17:49:18 volumio volumio[1099]: info: ------------------------------ 71ms Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioGetQueue Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::getQueue Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getQueue Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioGetQueue Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::getQueue Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getQueue Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioGetQueue Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::getQueue Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getQueue Sep 15 17:49:18 volumio volumio[1099]: info: CoreCommandRouter::volumioGetQueue Sep 15 17:49:18 volumio volumio[1099]: info: CoreStateMachine::getQueue Sep 15 17:49:18 volumio volumio[1099]: info: CorePlayQueue::getQueue Sep 15 17:49:19 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 22. Sep 15 17:49:19 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:19 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:19 volumio volumio[2332]: Traceback (most recent call last): Sep 15 17:49:19 volumio volumio[2332]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:19 volumio volumio[2332]: from aiohttp import web Sep 15 17:49:19 volumio volumio[2332]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:20 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 17:49:20 volumio volumio[1099]: info: Preload queue cleared Sep 15 17:49:21 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 23. Sep 15 17:49:21 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:21 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:21 volumio volumio[2354]: Traceback (most recent call last): Sep 15 17:49:21 volumio volumio[2354]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:21 volumio volumio[2354]: from aiohttp import web Sep 15 17:49:21 volumio volumio[2354]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:23 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 17:49:23 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 24. Sep 15 17:49:23 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:23 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:23 volumio volumio[2361]: Traceback (most recent call last): Sep 15 17:49:23 volumio volumio[2361]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:23 volumio volumio[2361]: from aiohttp import web Sep 15 17:49:23 volumio volumio[2361]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:23 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:23 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:24 volumio volumio[1099]: info: Preload queue cleared Sep 15 17:49:26 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:26 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 25. Sep 15 17:49:26 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:26 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:26 volumio volumio[2362]: Traceback (most recent call last): Sep 15 17:49:26 volumio volumio[2362]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:26 volumio volumio[2362]: from aiohttp import web Sep 15 17:49:26 volumio volumio[2362]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:26 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:26 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:28 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:28 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 26. Sep 15 17:49:28 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:28 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:28 volumio volumio[2363]: Traceback (most recent call last): Sep 15 17:49:28 volumio volumio[2363]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:28 volumio volumio[2363]: from aiohttp import web Sep 15 17:49:28 volumio volumio[2363]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:30 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 27. Sep 15 17:49:30 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:30 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:30 volumio volumio[2364]: Traceback (most recent call last): Sep 15 17:49:30 volumio volumio[2364]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:30 volumio volumio[2364]: from aiohttp import web Sep 15 17:49:30 volumio volumio[2364]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:30 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:30 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:31 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:49:31 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:49:31 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:49:31 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:49:31 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:49:32 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:32 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 28. Sep 15 17:49:32 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:32 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:32 volumio volumio[2382]: Traceback (most recent call last): Sep 15 17:49:32 volumio volumio[2382]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:32 volumio volumio[2382]: from aiohttp import web Sep 15 17:49:32 volumio volumio[2382]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:32 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:32 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:35 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:35 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 29. Sep 15 17:49:35 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:35 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:35 volumio volumio[2383]: Traceback (most recent call last): Sep 15 17:49:35 volumio volumio[2383]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:35 volumio volumio[2383]: from aiohttp import web Sep 15 17:49:35 volumio volumio[2383]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:35 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:35 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:35 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 17:49:35 volumio volumio[1099]: info: Preload queue cleared Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i6981 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i9628 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i12294 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i14769 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i17145 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i19316 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i21462 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i23554 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i25610 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i27539 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i29282 Sep 15 17:49:35 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i30729 Sep 15 17:49:35 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i6981 in service upnp_browser Sep 15 17:49:35 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i9628 in service upnp_browser Sep 15 17:49:35 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i12294 in service upnp_browser Sep 15 17:49:35 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i14769 in service upnp_browser Sep 15 17:49:35 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i17145 in service upnp_browser Sep 15 17:49:35 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i19316 in service upnp_browser Sep 15 17:49:35 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i21462 in service upnp_browser Sep 15 17:49:35 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i23554 in service upnp_browser Sep 15 17:49:36 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i25610 in service upnp_browser Sep 15 17:49:36 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i27539 in service upnp_browser Sep 15 17:49:36 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i29282 in service upnp_browser Sep 15 17:49:36 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$*i30729 in service upnp_browser Sep 15 17:49:37 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:37 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 30. Sep 15 17:49:37 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:37 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:37 volumio volumio[2384]: Traceback (most recent call last): Sep 15 17:49:37 volumio volumio[2384]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:37 volumio volumio[2384]: from aiohttp import web Sep 15 17:49:37 volumio volumio[2384]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:37 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:37 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:39 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 31. Sep 15 17:49:39 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:39 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:39 volumio volumio[2386]: Traceback (most recent call last): Sep 15 17:49:39 volumio volumio[2386]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:39 volumio volumio[2386]: from aiohttp import web Sep 15 17:49:39 volumio volumio[2386]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:39 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:39 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:40 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 17:49:40 volumio volumio[1099]: info: Preload queue cleared Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81913 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81889 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81901 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81896 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81917 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81875 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81893 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81906 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81910 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81914 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81908 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81885 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81922 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81919 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81909 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81897 Sep 15 17:49:40 volumio volumio[1099]: info: Preloading song: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81879 Sep 15 17:49:40 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81913 in service upnp_browser Sep 15 17:49:40 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81889 in service upnp_browser Sep 15 17:49:40 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81901 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81896 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81917 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81875 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81893 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81906 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81910 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81914 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81908 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81885 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81922 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81919 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81909 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81897 in service upnp_browser Sep 15 17:49:41 volumio volumio[1099]: info: Exploding uri upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81879 in service upnp_browser Sep 15 17:49:41 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:41 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 32. Sep 15 17:49:41 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:41 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:41 volumio volumio[2401]: Traceback (most recent call last): Sep 15 17:49:41 volumio volumio[2401]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:41 volumio volumio[2401]: from aiohttp import web Sep 15 17:49:41 volumio volumio[2401]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:41 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:41 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:42 volumio volumio[1099]: info: Preload queue cleared Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::ClearQueue Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::updateTrackBlock Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrackBlock Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::stPlaybackTimer Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::serviceStop Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::serviceStop Sep 15 17:49:42 volumio volumio[1099]: info: [1757958582388] ControllerUPNPBrowser::stop Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::clearPlayQueue Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::saveQueue Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushQueue Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::addQueueItems Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::addQueueItems Sep 15 17:49:42 volumio volumio[1099]: info: Preload queue cleared Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/folder/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*p7542 Sep 15 17:49:42 volumio volumio[1099]: info: Exploding uri upnp/folder/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*p7542 in service upnp_browser Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81913 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81913 Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand stop took 26 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 5 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 3 milliseconds Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wild Heart","artist":"Luba","album":"All Or Nothing","uri":"http://192.168.1.30:9790/minimserver/*/Musik/Music/1980s*20albums/1980s*20albums/01*20-*20Wild*20Heart.mp3","trackType":"mp3"} Sep 15 17:49:42 volumio volumio[1099]: verbose: CURRENT POSITION 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: No code Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wild Heart","artist":"Luba","album":"All Or Nothing","uri":"http://192.168.1.30:9790/minimserver/*/Musik/Music/1980s*20albums/1980s*20albums/01*20-*20Wild*20Heart.mp3","trackType":"mp3"} Sep 15 17:49:42 volumio volumio[1099]: verbose: CURRENT POSITION 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: No code Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wild Heart","artist":"Luba","album":"All Or Nothing","uri":"http://192.168.1.30:9790/minimserver/*/Musik/Music/1980s*20albums/1980s*20albums/01*20-*20Wild*20Heart.mp3","trackType":"mp3"} Sep 15 17:49:42 volumio volumio[1099]: verbose: CURRENT POSITION 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: No code Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wild Heart","artist":"Luba","album":"All Or Nothing","uri":"http://192.168.1.30:9790/minimserver/*/Musik/Music/1980s*20albums/1980s*20albums/01*20-*20Wild*20Heart.mp3","trackType":"mp3"} Sep 15 17:49:42 volumio volumio[1099]: verbose: CURRENT POSITION 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: No code Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 0 Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 193ms Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 192ms Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 192ms Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 194ms Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushQueue Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::saveQueue Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::updateTrackBlock Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrackBlock Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPlay Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::play index 1 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::addQueueItems Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::addQueueItems Sep 15 17:49:42 volumio volumio[1099]: info: Preload queue cleared Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81889 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81889 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81901 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81901 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81896 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81896 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81917 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81917 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81875 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81875 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81893 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81893 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81906 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81906 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81910 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81910 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81914 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81914 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81908 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81908 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81885 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81885 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81922 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81922 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81919 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81919 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81909 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81909 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81897 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81897 Sep 15 17:49:42 volumio volumio[1099]: info: Adding Item to queue: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81879 Sep 15 17:49:42 volumio volumio[1099]: info: Using cached record of: upnp/http://192.168.1.30:9791/da6fa564-7cc5-45fa-9b06-8951d854641b/upnp.org-ContentDirectory-1/control@0$folders$f16371$f16883$f16896$*i81879 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::stop Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushQueue Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::saveQueue Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::play index undefined Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::updateTrackBlock Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrackBlock Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::startPlaybackTimer Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:42 volumio volumio[1099]: info: [1757958582656] ControllerUPNPBrowser::clearAddPlayTrack Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand stop took 4 milliseconds Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:49:42 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand clear took 31 milliseconds Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav" Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:49:42 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:49:42 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:49:42 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:49:42 volumio volumio[1099]: error: updateQueue error: null Sep 15 17:49:42 volumio volumio[1099]: error: updateQueue error: null Sep 15 17:49:42 volumio volumio[1099]: error: updateQueue error: null Sep 15 17:49:42 volumio volumio[1099]: error: updateQueue error: null Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 22ms Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav" Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 12ms Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 12ms Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 11ms Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:49:42 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand add "http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav" took 10 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand play Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:49:42 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:49:42 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:49:42 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 19ms Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand play took 15 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 19ms Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 18ms Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 18ms Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 17:49:42 volumio volumio[1099]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 17:49:42 volumio volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 56 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 50 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 45 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 43 milliseconds Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: Sep 15 17:49:42 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 42 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 40 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 40 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 40 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 40 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 40 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 35 milliseconds Sep 15 17:49:42 volumio volumio[1099]: info: sendMpdCommand status took 27 milliseconds Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:49:42 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:42 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20Ed*20Starink*20Equinoxe*20Part*204.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav","trackType":"wav"} Sep 15 17:49:42 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:42 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20Ed*20Starink*20Equinoxe*20Part*204.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav","trackType":"wav"} Sep 15 17:49:42 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:49:42 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:42 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20Ed*20Starink*20Equinoxe*20Part*204.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav","trackType":"wav"} Sep 15 17:49:42 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:49:42 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:42 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:42 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20Ed*20Starink*20Equinoxe*20Part*204.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav","trackType":"wav"} Sep 15 17:49:42 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:49:42 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:42 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:42 volumio volumio[1099]: info: ------------------------------ 190ms Sep 15 17:49:43 volumio volumio[1099]: info: ------------------------------ 305ms Sep 15 17:49:43 volumio volumio[1099]: info: ------------------------------ 307ms Sep 15 17:49:43 volumio volumio[1099]: info: ------------------------------ 302ms Sep 15 17:49:43 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 238 milliseconds Sep 15 17:49:43 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 238 milliseconds Sep 15 17:49:43 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 233 milliseconds Sep 15 17:49:43 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 236 milliseconds Sep 15 17:49:43 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:49:43 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:43 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:43 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1343,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20Ed*20Starink*20Equinoxe*20Part*204.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav","trackType":"wav"} Sep 15 17:49:43 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:49:43 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:43 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:43 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:43 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1343,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20Ed*20Starink*20Equinoxe*20Part*204.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav","trackType":"wav"} Sep 15 17:49:43 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:49:43 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:43 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:43 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:43 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1343,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20Ed*20Starink*20Equinoxe*20Part*204.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav","trackType":"wav"} Sep 15 17:49:43 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:49:43 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:43 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:49:43 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:49:43 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1343,"duration":264,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20Ed*20Starink*20Equinoxe*20Part*204.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/02*20Ed*20Starink*20Equinoxe*20Part*204.wav","trackType":"wav"} Sep 15 17:49:43 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:49:43 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:49:43 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:49:43 volumio volumio[1099]: info: ------------------------------ 560ms Sep 15 17:49:43 volumio volumio[1099]: info: ------------------------------ 549ms Sep 15 17:49:43 volumio volumio[1099]: info: ------------------------------ 549ms Sep 15 17:49:43 volumio volumio[1099]: info: ------------------------------ 547ms Sep 15 17:49:43 volumio volumio[1099]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 15 17:49:44 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:44 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 33. Sep 15 17:49:44 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:44 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:44 volumio volumio[2414]: Traceback (most recent call last): Sep 15 17:49:44 volumio volumio[2414]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:44 volumio volumio[2414]: from aiohttp import web Sep 15 17:49:44 volumio volumio[2414]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:44 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:44 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:46 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:46 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 34. Sep 15 17:49:46 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:46 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:46 volumio volumio[2419]: Traceback (most recent call last): Sep 15 17:49:46 volumio volumio[2419]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:46 volumio volumio[2419]: from aiohttp import web Sep 15 17:49:46 volumio volumio[2419]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:46 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:46 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:48 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 35. Sep 15 17:49:48 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:48 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:48 volumio volumio[2424]: Traceback (most recent call last): Sep 15 17:49:48 volumio volumio[2424]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:48 volumio volumio[2424]: from aiohttp import web Sep 15 17:49:48 volumio volumio[2424]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:50 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:50 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 36. Sep 15 17:49:50 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:50 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:50 volumio volumio[2429]: Traceback (most recent call last): Sep 15 17:49:50 volumio volumio[2429]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:50 volumio volumio[2429]: from aiohttp import web Sep 15 17:49:50 volumio volumio[2429]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:50 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:50 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:51 volumio volumio[1099]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 15 17:49:51 volumio volumio[1099]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Sep 15 17:49:53 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:53 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 37. Sep 15 17:49:53 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:53 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:53 volumio volumio[2451]: Traceback (most recent call last): Sep 15 17:49:53 volumio volumio[2451]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:53 volumio volumio[2451]: from aiohttp import web Sep 15 17:49:53 volumio volumio[2451]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:53 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:53 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:55 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:55 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 38. Sep 15 17:49:55 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:55 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:55 volumio volumio[2458]: Traceback (most recent call last): Sep 15 17:49:55 volumio volumio[2458]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:55 volumio volumio[2458]: from aiohttp import web Sep 15 17:49:55 volumio volumio[2458]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:55 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:55 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:57 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:57 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 39. Sep 15 17:49:57 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:57 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:57 volumio volumio[2463]: Traceback (most recent call last): Sep 15 17:49:57 volumio volumio[2463]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:57 volumio volumio[2463]: from aiohttp import web Sep 15 17:49:57 volumio volumio[2463]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:49:59 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:49:59 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 40. Sep 15 17:49:59 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:49:59 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:49:59 volumio volumio[2468]: Traceback (most recent call last): Sep 15 17:49:59 volumio volumio[2468]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:49:59 volumio volumio[2468]: from aiohttp import web Sep 15 17:49:59 volumio volumio[2468]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:49:59 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:49:59 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:50:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:50:01 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:50:01 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:50:01 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:50:02 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:02 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 41. Sep 15 17:50:02 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:02 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:02 volumio volumio[2487]: Traceback (most recent call last): Sep 15 17:50:02 volumio volumio[2487]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:02 volumio volumio[2487]: from aiohttp import web Sep 15 17:50:02 volumio volumio[2487]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:02 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:02 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:04 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:04 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 42. Sep 15 17:50:04 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:04 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:04 volumio volumio[2494]: Traceback (most recent call last): Sep 15 17:50:04 volumio volumio[2494]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:04 volumio volumio[2494]: from aiohttp import web Sep 15 17:50:04 volumio volumio[2494]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:04 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:04 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioNext Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::next Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::next Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand next Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand next took 3 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 4 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 3 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 2 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 1 milliseconds Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 1 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 1 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::play index undefined Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: No code Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: No code Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService stop Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: No code Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::startPlaybackTimer Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: [1757958605317] ControllerUPNPBrowser::clearAddPlayTrack Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand stop Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 178ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 177ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 181ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 180ms Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand stop took 34 milliseconds Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand clear Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:50:05 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand clear took 27 milliseconds Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav" Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:50:05 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:50:05 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:50:05 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:50:05 volumio volumio[1099]: error: updateQueue error: null Sep 15 17:50:05 volumio volumio[1099]: error: updateQueue error: null Sep 15 17:50:05 volumio volumio[1099]: error: updateQueue error: null Sep 15 17:50:05 volumio volumio[1099]: error: updateQueue error: null Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 35ms Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav" Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 20ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 19ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 21ms Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:50:05 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand add "http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav" took 8 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand play Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:50:05 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:50:05 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces system playlist update Sep 15 17:50:05 volumio volumio[1099]: info: Ignoring MPD Status Update Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 9ms Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand play took 8 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 7ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 6ms Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 21 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:50:05 volumio volumio[1099]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 15 17:50:05 volumio volumio[1099]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 15 17:50:05 volumio volumio[1099]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 33 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 29 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 23 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 16 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","trackType":"wav"} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus stop Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 59ms Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: Sep 15 17:50:05 volumio volumio[1099]: ---------------------------- MPD announces state update: player Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::getState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand status Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","trackType":"wav"} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:50:05 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":956,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","trackType":"wav"} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:50:05 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":956,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","trackType":"wav"} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:50:05 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":956,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","trackType":"wav"} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:50:05 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 293ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 296ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 294ms Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 284ms Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 258 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 258 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand status took 254 milliseconds Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseState Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 65 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 67 milliseconds Sep 15 17:50:05 volumio volumio[1099]: info: sendMpdCommand playlistinfo took 66 milliseconds Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:50:05 volumio volumio[1099]: verbose: ControllerMpd::parseTrackInfo Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":956,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","trackType":"wav"} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:50:05 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":956,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","trackType":"wav"} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:50:05 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ControllerMpd::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::servicePushState Sep 15 17:50:05 volumio volumio[1099]: info: CorePlayQueue::getTrack 2 Sep 15 17:50:05 volumio volumio[1099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":956,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","artist":null,"album":null,"uri":"http://192.168.1.30:9790/minimserver/*/Musik/Singles*20in*20wav/wav*20music/Synthesizer*20Greatest*20Volume*202/03*20Ed*20Starink*20Maid*20Of*20Orleans.wav","trackType":"wav"} Sep 15 17:50:05 volumio volumio[1099]: verbose: CURRENT POSITION 2 Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState stateService play Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::syncState currentStatus play Sep 15 17:50:05 volumio volumio[1099]: info: Received an update from plugin. extracting info from payload Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreStateMachine::pushState Sep 15 17:50:05 volumio volumio[1099]: info: CoreCommandRouter::volumioPushState Sep 15 17:50:05 volumio volumio[1099]: info: ------------------------------ 482ms Sep 15 17:50:06 volumio volumio[1099]: info: ------------------------------ 484ms Sep 15 17:50:06 volumio volumio[1099]: info: ------------------------------ 484ms Sep 15 17:50:06 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 43. Sep 15 17:50:06 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:06 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:06 volumio volumio[2554]: Traceback (most recent call last): Sep 15 17:50:06 volumio volumio[2554]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:06 volumio volumio[2554]: from aiohttp import web Sep 15 17:50:06 volumio volumio[2554]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:06 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:06 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:08 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 44. Sep 15 17:50:08 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:08 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:08 volumio volumio[2562]: Traceback (most recent call last): Sep 15 17:50:08 volumio volumio[2562]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:08 volumio volumio[2562]: from aiohttp import web Sep 15 17:50:08 volumio volumio[2562]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:10 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:50:11 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:11 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 45. Sep 15 17:50:11 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:11 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:11 volumio volumio[2567]: Traceback (most recent call last): Sep 15 17:50:11 volumio volumio[2567]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:11 volumio volumio[2567]: from aiohttp import web Sep 15 17:50:11 volumio volumio[2567]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:11 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:11 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:11 volumio sudo[2569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 17:50:11 volumio sudo[2569]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 17:50:11 volumio sudo[2569]: pam_unix(sudo:session): session closed for user root Sep 15 17:50:11 volumio sudo[2572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 17:50:11 volumio sudo[2572]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 17:50:11 volumio sudo[2572]: pam_unix(sudo:session): session closed for user root Sep 15 17:50:11 volumio volumio[1099]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 15; TB330FU Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.160 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 15 17:50:12 volumio sudo[2594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 17:50:12 volumio sudo[2594]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 17:50:12 volumio sudo[2594]: pam_unix(sudo:session): session closed for user root Sep 15 17:50:12 volumio sudo[2597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 17:50:12 volumio sudo[2597]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 17:50:12 volumio sudo[2597]: pam_unix(sudo:session): session closed for user root Sep 15 17:50:12 volumio volumio[1099]: verbose: New Socket.io Connection to 192.168.1.7 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 15; TB330FU Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.160 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::volumioGetQueue Sep 15 17:50:12 volumio volumio[1099]: info: CoreStateMachine::getQueue Sep 15 17:50:12 volumio volumio[1099]: info: CorePlayQueue::getQueue Sep 15 17:50:12 volumio volumio[1099]: info: Listing playlists Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 15 17:50:12 volumio volumio[1099]: info: Received Get System Info Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:50:12 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 15 17:50:12 volumio volumio[1099]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 17:50:12 volumio volumio[1099]: info: FusionDsp - no preset used Sep 15 17:50:13 volumio volumio[1099]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,.json Sep 15 17:50:13 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:13 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 46. Sep 15 17:50:13 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:13 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:13 volumio volumio[2601]: Traceback (most recent call last): Sep 15 17:50:13 volumio volumio[2601]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:13 volumio volumio[2601]: from aiohttp import web Sep 15 17:50:13 volumio volumio[2601]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:13 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:13 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:14 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 17:50:14 volumio volumio[1099]: info: Received Get System Info Sep 15 17:50:14 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:50:14 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:50:14 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:50:14 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:50:14 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:50:14 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 15 17:50:15 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 17:50:15 volumio volumio[1099]: info: Received Get System Info Sep 15 17:50:15 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 17:50:15 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 17:50:15 volumio volumio[1099]: info: Discovery: Getting this device information Sep 15 17:50:15 volumio volumio[1099]: info: CoreCommandRouter::volumioGetState Sep 15 17:50:15 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 17:50:15 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:15 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 47. Sep 15 17:50:15 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:15 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:15 volumio volumio[2606]: Traceback (most recent call last): Sep 15 17:50:15 volumio volumio[2606]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:15 volumio volumio[2606]: from aiohttp import web Sep 15 17:50:15 volumio volumio[2606]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:15 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:15 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:17 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:17 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 48. Sep 15 17:50:17 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:17 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:17 volumio volumio[2613]: Traceback (most recent call last): Sep 15 17:50:17 volumio volumio[2613]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:17 volumio volumio[2613]: from aiohttp import web Sep 15 17:50:17 volumio volumio[2613]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:20 volumio systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Sep 15 17:50:20 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 49. Sep 15 17:50:20 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 15 17:50:20 volumio systemd[1]: Started FusionDsp Daemon. Sep 15 17:50:20 volumio volumio[2618]: Traceback (most recent call last): Sep 15 17:50:20 volumio volumio[2618]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Sep 15 17:50:20 volumio volumio[2618]: from aiohttp import web Sep 15 17:50:20 volumio volumio[2618]: ModuleNotFoundError: No module named 'aiohttp' Sep 15 17:50:20 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Sep 15 17:50:20 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Sep 15 17:50:21 volumio volumio[1099]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 15 17:50:21 volumio volumio[1099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 17:50:21 volumio volumio[1099]: TypeError: Cannot read property 'length' of undefined Sep 15 17:50:21 volumio volumio[1099]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 15 17:50:21 volumio volumio[1099]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 15 17:50:21 volumio volumio[1099]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 15 17:50:21 volumio volumio[1099]: at Parser.emit (events.js:400:28) Sep 15 17:50:21 volumio volumio[1099]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 15 17:50:21 volumio volumio[1099]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 15 17:50:21 volumio volumio[1099]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 15 17:50:21 volumio volumio[1099]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 15 17:50:21 volumio volumio[1099]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 15 17:50:21 volumio volumio[1099]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 15 17:50:21 volumio volumio[1099]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 15 17:50:21 volumio volumio[1099]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 15 17:50:21 volumio volumio[1099]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 15 17:50:21 volumio volumio[1099]: at IncomingMessage.emit (events.js:412:35) Sep 15 17:50:21 volumio volumio[1099]: at endReadableNT (internal/streams/readable.js:1333:12) Sep 15 17:50:21 volumio volumio[1099]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 15 17:50:21 volumio volumio[1099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 17:50:21 volumio sudo[2644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 17:49 Sep 15 17:50:21 volumio sudo[2644]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"