Jul 12 02:43:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 758. Jul 12 02:43:01 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:01 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:01 volumio volumio[12764]: Traceback (most recent call last): Jul 12 02:43:01 volumio volumio[12764]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:01 volumio volumio[12764]: from aiohttp import web Jul 12 02:43:01 volumio volumio[12764]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 759. Jul 12 02:43:03 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:03 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:03 volumio volumio[12774]: Traceback (most recent call last): Jul 12 02:43:03 volumio volumio[12774]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:03 volumio volumio[12774]: from aiohttp import web Jul 12 02:43:03 volumio volumio[12774]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:03 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:03 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:05 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 760. Jul 12 02:43:05 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:05 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:05 volumio volumio[12783]: Traceback (most recent call last): Jul 12 02:43:05 volumio volumio[12783]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:05 volumio volumio[12783]: from aiohttp import web Jul 12 02:43:05 volumio volumio[12783]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:05 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:05 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:07 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 761. Jul 12 02:43:07 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:07 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:07 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 12 02:43:07 volumio dbus-daemon[856]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.84' (uid=0 pid=12807 comm="timedatectl show --property=NTPSynchronized --valu") Jul 12 02:43:07 volumio volumio[12806]: Traceback (most recent call last): Jul 12 02:43:07 volumio volumio[12806]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:07 volumio volumio[12806]: from aiohttp import web Jul 12 02:43:07 volumio volumio[12806]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:07 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 12 02:43:07 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:07 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:08 volumio dbus-daemon[856]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 12 02:43:08 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 12 02:43:08 volumio setdatetime-helper.sh[12805]: Time is already synchronized. Jul 12 02:43:08 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 12 02:43:08 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 12 02:43:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 762. Jul 12 02:43:10 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:10 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:10 volumio volumio[12817]: Traceback (most recent call last): Jul 12 02:43:10 volumio volumio[12817]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:10 volumio volumio[12817]: from aiohttp import web Jul 12 02:43:10 volumio volumio[12817]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 763. Jul 12 02:43:12 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:12 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:12 volumio volumio[12827]: Traceback (most recent call last): Jul 12 02:43:12 volumio volumio[12827]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:12 volumio volumio[12827]: from aiohttp import web Jul 12 02:43:12 volumio volumio[12827]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:13 volumio volumio[1095]: info: CorePlayQueue::getTrack 350 Jul 12 02:43:13 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:13 volumio volumio[1095]: info: Prefetching next song Jul 12 02:43:13 volumio volumio[1095]: info: DOING PREFETCH IN MPD Jul 12 02:43:13 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3" Jul 12 02:43:13 volumio volumio[1095]: info: Jul 12 02:43:13 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:43:13 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:43:13 volumio volumio[1095]: info: sendMpdCommand add "USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3" took 1 milliseconds Jul 12 02:43:13 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 12 02:43:13 volumio volumio[1095]: info: Jul 12 02:43:13 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:43:13 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:43:13 volumio volumio[1095]: info: Jul 12 02:43:13 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:43:13 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:43:13 volumio volumio[1095]: info: Jul 12 02:43:13 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:43:13 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:43:13 volumio volumio[1095]: info: ------------------------------ 2ms Jul 12 02:43:13 volumio volumio[1095]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 12 02:43:13 volumio volumio[1095]: info: ------------------------------ 2ms Jul 12 02:43:13 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:43:13 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:43:14 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 764. Jul 12 02:43:14 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:14 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:14 volumio volumio[12838]: Traceback (most recent call last): Jul 12 02:43:14 volumio volumio[12838]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:14 volumio volumio[12838]: from aiohttp import web Jul 12 02:43:14 volumio volumio[12838]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:16 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 765. Jul 12 02:43:16 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:16 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:16 volumio volumio[12846]: Traceback (most recent call last): Jul 12 02:43:16 volumio volumio[12846]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:16 volumio volumio[12846]: from aiohttp import web Jul 12 02:43:16 volumio volumio[12846]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:16 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:16 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::startPlaybackTimer Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:43:18 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:43:18 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:43:18 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:43:18 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 3ms Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand status took 1 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:43:18 volumio volumio[1095]: info: Jul 12 02:43:18 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand status took 3 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 3ms Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand status took 1 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand status took 1 milliseconds Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:43:18 volumio volumio[1095]: verbose: CURRENT POSITION 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:43:18 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:43:18 volumio volumio[1095]: verbose: CURRENT POSITION 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:43:18 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 10ms Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 9ms Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand status took 7 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:43:18 volumio volumio[1095]: verbose: CURRENT POSITION 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:43:18 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:43:18 volumio volumio[1095]: verbose: CURRENT POSITION 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:43:18 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:43:18 volumio volumio[1095]: verbose: CURRENT POSITION 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:43:18 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:43:18 volumio volumio[1095]: verbose: CURRENT POSITION 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:43:18 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:43:18 volumio volumio[1095]: verbose: CURRENT POSITION 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:43:18 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 26ms Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 26ms Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 25ms Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 25ms Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 25ms Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 30 milliseconds Jul 12 02:43:18 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:43:18 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:43:18 volumio volumio[1095]: verbose: CURRENT POSITION 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:43:18 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: ------------------------------ 43ms Jul 12 02:43:18 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:43:18 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:43:18 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:18 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:43:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 766. Jul 12 02:43:19 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:19 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:19 volumio volumio[12870]: Traceback (most recent call last): Jul 12 02:43:19 volumio volumio[12870]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:19 volumio volumio[12870]: from aiohttp import web Jul 12 02:43:19 volumio volumio[12870]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 767. Jul 12 02:43:21 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:21 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:21 volumio volumio[12880]: Traceback (most recent call last): Jul 12 02:43:21 volumio volumio[12880]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:21 volumio volumio[12880]: from aiohttp import web Jul 12 02:43:21 volumio volumio[12880]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 768. Jul 12 02:43:23 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:23 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:23 volumio volumio[12891]: Traceback (most recent call last): Jul 12 02:43:23 volumio volumio[12891]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:23 volumio volumio[12891]: from aiohttp import web Jul 12 02:43:23 volumio volumio[12891]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:23 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:23 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:25 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 769. Jul 12 02:43:25 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:25 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:25 volumio volumio[12899]: Traceback (most recent call last): Jul 12 02:43:25 volumio volumio[12899]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:25 volumio volumio[12899]: from aiohttp import web Jul 12 02:43:25 volumio volumio[12899]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:25 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:25 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:28 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 770. Jul 12 02:43:28 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:28 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:28 volumio volumio[12921]: Traceback (most recent call last): Jul 12 02:43:28 volumio volumio[12921]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:28 volumio volumio[12921]: from aiohttp import web Jul 12 02:43:28 volumio volumio[12921]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:28 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:28 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:30 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 771. Jul 12 02:43:30 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:30 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:30 volumio volumio[12929]: Traceback (most recent call last): Jul 12 02:43:30 volumio volumio[12929]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:30 volumio volumio[12929]: from aiohttp import web Jul 12 02:43:30 volumio volumio[12929]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:30 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:30 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:32 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 772. Jul 12 02:43:32 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:32 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:32 volumio volumio[12940]: Traceback (most recent call last): Jul 12 02:43:32 volumio volumio[12940]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:32 volumio volumio[12940]: from aiohttp import web Jul 12 02:43:32 volumio volumio[12940]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:32 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:32 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:34 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 773. Jul 12 02:43:34 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:34 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:34 volumio volumio[12948]: Traceback (most recent call last): Jul 12 02:43:34 volumio volumio[12948]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:34 volumio volumio[12948]: from aiohttp import web Jul 12 02:43:34 volumio volumio[12948]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:34 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:34 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:37 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 774. Jul 12 02:43:37 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:37 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:37 volumio volumio[12970]: Traceback (most recent call last): Jul 12 02:43:37 volumio volumio[12970]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:37 volumio volumio[12970]: from aiohttp import web Jul 12 02:43:37 volumio volumio[12970]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:37 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:37 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:38 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 12 02:43:38 volumio ntpd[1016]: CLOCK: time stepped by -0.348104 Jul 12 02:43:38 volumio ntpd[1016]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Jul 12 02:43:39 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 775. Jul 12 02:43:39 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:39 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:39 volumio volumio[12980]: Traceback (most recent call last): Jul 12 02:43:39 volumio volumio[12980]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:39 volumio volumio[12980]: from aiohttp import web Jul 12 02:43:39 volumio volumio[12980]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:39 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:39 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:41 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 776. Jul 12 02:43:41 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:41 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:41 volumio volumio[12989]: Traceback (most recent call last): Jul 12 02:43:41 volumio volumio[12989]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:41 volumio volumio[12989]: from aiohttp import web Jul 12 02:43:41 volumio volumio[12989]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:41 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:41 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:43 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 777. Jul 12 02:43:43 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:43 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:43 volumio volumio[12999]: Traceback (most recent call last): Jul 12 02:43:43 volumio volumio[12999]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:43 volumio volumio[12999]: from aiohttp import web Jul 12 02:43:43 volumio volumio[12999]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:43 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:43 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:45 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 778. Jul 12 02:43:45 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:45 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:45 volumio volumio[13007]: Traceback (most recent call last): Jul 12 02:43:45 volumio volumio[13007]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:45 volumio volumio[13007]: from aiohttp import web Jul 12 02:43:45 volumio volumio[13007]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:45 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:45 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:48 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 779. Jul 12 02:43:48 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:48 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:48 volumio volumio[13029]: Traceback (most recent call last): Jul 12 02:43:48 volumio volumio[13029]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:48 volumio volumio[13029]: from aiohttp import web Jul 12 02:43:48 volumio volumio[13029]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:48 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:48 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:50 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 780. Jul 12 02:43:50 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:50 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:50 volumio volumio[13038]: Traceback (most recent call last): Jul 12 02:43:50 volumio volumio[13038]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:50 volumio volumio[13038]: from aiohttp import web Jul 12 02:43:50 volumio volumio[13038]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:50 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:50 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:52 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 781. Jul 12 02:43:52 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:52 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:52 volumio volumio[13048]: Traceback (most recent call last): Jul 12 02:43:52 volumio volumio[13048]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:52 volumio volumio[13048]: from aiohttp import web Jul 12 02:43:52 volumio volumio[13048]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:52 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:52 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:54 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 782. Jul 12 02:43:54 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:54 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:54 volumio volumio[13056]: Traceback (most recent call last): Jul 12 02:43:54 volumio volumio[13056]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:54 volumio volumio[13056]: from aiohttp import web Jul 12 02:43:54 volumio volumio[13056]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:54 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:54 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:57 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 783. Jul 12 02:43:57 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:57 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:57 volumio volumio[13078]: Traceback (most recent call last): Jul 12 02:43:57 volumio volumio[13078]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:57 volumio volumio[13078]: from aiohttp import web Jul 12 02:43:57 volumio volumio[13078]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:57 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:57 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:43:59 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 784. Jul 12 02:43:59 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:59 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:43:59 volumio volumio[13087]: Traceback (most recent call last): Jul 12 02:43:59 volumio volumio[13087]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:43:59 volumio volumio[13087]: from aiohttp import web Jul 12 02:43:59 volumio volumio[13087]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:43:59 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:43:59 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:01 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 785. Jul 12 02:44:01 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:01 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:01 volumio volumio[13098]: Traceback (most recent call last): Jul 12 02:44:01 volumio volumio[13098]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:01 volumio volumio[13098]: from aiohttp import web Jul 12 02:44:01 volumio volumio[13098]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:01 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:01 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:03 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 786. Jul 12 02:44:03 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:03 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:03 volumio volumio[13106]: Traceback (most recent call last): Jul 12 02:44:03 volumio volumio[13106]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:03 volumio volumio[13106]: from aiohttp import web Jul 12 02:44:03 volumio volumio[13106]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:03 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:03 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:04 volumio thd[12159]: {"time":1752288072263,"response":"next Success"} Jul 12 02:44:04 volumio thd[862]: Executing trigger action: /usr/local/bin/volumio next Jul 12 02:44:04 volumio thd[13110]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 12 02:44:04 volumio thd[13110]: Dload Upload Total Spent Left Speed Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- Client requests Volumio next Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::volumioNext Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::next Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::stop Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::stPlaybackTimer Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::updateTrackBlock Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrackBlock Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::serviceStop Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 249 Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::serviceStop Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::stop Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 02:44:04 volumio thd[13110]: [158B blob data] Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - Volumio is not playing Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - Clipped samples monitor stopped Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand stop took 8 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::play index undefined Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::startPlaybackTimer Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3 Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::updateTrackBlock Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrackBlock Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand status took 4 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand stop took 3 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:04 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand status took 4 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand status took 3 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand clear took 2 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3" Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:04 volumio volumio[1095]: 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":"眼红红","artist":"Lawrence","album":"现在钢琴集","uri":"USB/500g/mp3/网易云音乐-MP3/Lawrence - 眼红红.mp3","trackType":"mp3"} Jul 12 02:44:04 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::syncState stateService stop Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:04 volumio volumio[1095]: info: No code Jul 12 02:44:04 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:04 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:04 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:04 volumio volumio[1095]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 18ms Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 13ms Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:04 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:04 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:04 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:04 volumio volumio[1095]: error: updateQueue error: null Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:04 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 10ms Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand add "USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3" took 8 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand play Jul 12 02:44:04 volumio volumio[1095]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 12 02:44:04 volumio volumio[1095]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 14ms Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 13ms Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:04 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:04 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:04 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - Volumio is not playing Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - Clipped samples monitor stopped Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - Volumio is not playing Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - Clipped samples monitor stopped Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - Volumio is not playing Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - Clipped samples monitor stopped Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 4ms Jul 12 02:44:04 volumio volumio[1095]: info: sendMpdCommand play took 4 milliseconds Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 3ms Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 3ms Jul 12 02:44:04 volumio volumio[1095]: info: ------------------------------ 3ms Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:04 volumio volumio[1095]: info: Jul 12 02:44:04 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:04 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:04 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 12 02:44:04 volumio volumio[1095]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 02:44:05 volumio volumio[1095]: info: Jul 12 02:44:05 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand status took 1856 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand status took 1856 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand status took 1856 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand status took 1855 milliseconds Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:05 volumio volumio[1095]: info: Jul 12 02:44:05 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:05 volumio volumio[1095]: info: Jul 12 02:44:05 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:05 volumio volumio[1095]: info: Jul 12 02:44:05 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand status took 3 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand status took 1 milliseconds Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:05 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:05 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:05 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:05 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:05 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:05 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:05 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: ------------------------------ 1866ms Jul 12 02:44:05 volumio volumio[1095]: info: ------------------------------ 1873ms Jul 12 02:44:05 volumio volumio[1095]: info: ------------------------------ 1873ms Jul 12 02:44:05 volumio volumio[1095]: info: ------------------------------ 1873ms Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 12 02:44:05 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:05 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2714,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:05 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:05 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2714,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:05 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:05 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2714,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:05 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:05 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2714,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:05 volumio volumio[1095]: verbose: CURRENT POSITION 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:05 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:05 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:05 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:05 volumio volumio[1095]: info: ------------------------------ 35ms Jul 12 02:44:05 volumio volumio[1095]: info: ------------------------------ 34ms Jul 12 02:44:05 volumio volumio[1095]: info: ------------------------------ 34ms Jul 12 02:44:05 volumio volumio[1095]: info: ------------------------------ 35ms Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:05 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:06 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 787. Jul 12 02:44:06 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:06 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:06 volumio volumio[13136]: Traceback (most recent call last): Jul 12 02:44:06 volumio volumio[13136]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:06 volumio volumio[13136]: from aiohttp import web Jul 12 02:44:06 volumio volumio[13136]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:06 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:06 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:07 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 12 02:44:07 volumio dbus-daemon[856]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.86' (uid=0 pid=13156 comm="timedatectl show --property=NTPSynchronized --valu") Jul 12 02:44:07 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 12 02:44:07 volumio dbus-daemon[856]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 12 02:44:07 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 12 02:44:07 volumio setdatetime-helper.sh[13155]: Time is already synchronized. Jul 12 02:44:07 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 12 02:44:07 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 12 02:44:08 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 788. Jul 12 02:44:08 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:08 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:08 volumio volumio[13163]: Traceback (most recent call last): Jul 12 02:44:08 volumio volumio[13163]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:08 volumio volumio[13163]: from aiohttp import web Jul 12 02:44:08 volumio volumio[13163]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:08 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:08 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:08 volumio thd[13110]: {"time":1752288244026,"response":"next Success"} Jul 12 02:44:08 volumio thd[862]: Executing trigger action: /usr/local/bin/volumio next Jul 12 02:44:08 volumio thd[13167]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 12 02:44:08 volumio thd[13167]: Dload Upload Total Spent Left Speed Jul 12 02:44:08 volumio volumio[1095]: info: Jul 12 02:44:08 volumio volumio[1095]: ---------------------------- Client requests Volumio next Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::volumioNext Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::next Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::stop Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::stPlaybackTimer Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::updateTrackBlock Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrackBlock Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::serviceStop Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 150 Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::serviceStop Jul 12 02:44:08 volumio volumio[1095]: info: ControllerMpd::stop Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 02:44:08 volumio thd[13167]: [158B blob data] Jul 12 02:44:08 volumio volumio[1095]: info: FusionDsp - Volumio is not playing Jul 12 02:44:08 volumio volumio[1095]: info: FusionDsp - Clipped samples monitor stopped Jul 12 02:44:08 volumio volumio[1095]: info: Jul 12 02:44:08 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand stop took 4 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::play index undefined Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::startPlaybackTimer Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3 Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::updateTrackBlock Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrackBlock Jul 12 02:44:08 volumio volumio[1095]: info: Jul 12 02:44:08 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:08 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:08 volumio volumio[1095]: info: Jul 12 02:44:08 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:08 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:08 volumio volumio[1095]: info: Jul 12 02:44:08 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:08 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand status took 3 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand stop took 2 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand status took 1 milliseconds Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:08 volumio volumio[1095]: info: Jul 12 02:44:08 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:08 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand clear took 4 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 02:44:08 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3" Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:08 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:08 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:08 volumio volumio[1095]: 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":"话太普通","artist":"农夫/季欣霈","album":"富甲天下","uri":"USB/500g/mp3/网易云音乐-MP3/农夫,季欣霈 - 话太普通.mp3","trackType":"mp3"} Jul 12 02:44:08 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::syncState stateService stop Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:08 volumio volumio[1095]: info: No code Jul 12 02:44:08 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:08 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:08 volumio volumio[1095]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 12 02:44:08 volumio volumio[1095]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 12 02:44:08 volumio volumio[1095]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 14ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 14ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 14ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 14ms Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:09 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:09 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:09 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:09 volumio volumio[1095]: error: updateQueue error: null Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:09 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 11ms Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand add "USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3" took 8 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 2ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 1ms Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand play Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:09 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:09 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Jul 12 02:44:09 volumio volumio[1095]: info: Ignoring MPD Status Update Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is not playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipped samples monitor stopped Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is not playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipped samples monitor stopped Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is not playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipped samples monitor stopped Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 4ms Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand play took 3 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 3ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 3ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 3ms Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand status took 5 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand status took 4 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand status took 5 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand status took 4 milliseconds Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:09 volumio volumio[1095]: info: Jul 12 02:44:09 volumio volumio[1095]: ---------------------------- MPD announces state update: player Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::getState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand status took 3 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand status took 5 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand status took 5 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand status took 4 milliseconds Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseState Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"莫文蔚","album":"回蔚","uri":"USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3","trackType":"mp3"} Jul 12 02:44:09 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"莫文蔚","album":"回蔚","uri":"USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3","trackType":"mp3"} Jul 12 02:44:09 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:09 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"莫文蔚","album":"回蔚","uri":"USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3","trackType":"mp3"} Jul 12 02:44:09 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:09 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"莫文蔚","album":"回蔚","uri":"USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3","trackType":"mp3"} Jul 12 02:44:09 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:09 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 19ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 26ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 26ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 26ms Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 12 02:44:09 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:09 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"莫文蔚","album":"回蔚","uri":"USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3","trackType":"mp3"} Jul 12 02:44:09 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:09 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"莫文蔚","album":"回蔚","uri":"USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3","trackType":"mp3"} Jul 12 02:44:09 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:09 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"莫文蔚","album":"回蔚","uri":"USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3","trackType":"mp3"} Jul 12 02:44:09 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:09 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: ControllerMpd::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"外面的世界","artist":"莫文蔚","album":"回蔚","uri":"USB/500g/mp3/网易云音乐-MP3/莫文蔚 - 外面的世界.mp3","trackType":"mp3"} Jul 12 02:44:09 volumio volumio[1095]: verbose: CURRENT POSITION 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:09 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: CoreStateMachine::pushState Jul 12 02:44:09 volumio volumio[1095]: info: CorePlayQueue::getTrack 128 Jul 12 02:44:09 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 37ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 37ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 36ms Jul 12 02:44:09 volumio volumio[1095]: info: ------------------------------ 37ms Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Volumio is playing Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:09 volumio volumio[1095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 02:44:09 volumio volumio[1095]: Error: Client network socket disconnected before secure TLS connection was established Jul 12 02:44:09 volumio volumio[1095]: at connResetException (node:internal/errors:720:14) Jul 12 02:44:09 volumio volumio[1095]: at TLSSocket.onConnectEnd (node:_tls_wrap:1682:19) Jul 12 02:44:09 volumio volumio[1095]: at TLSSocket.emit (node:events:526:35) Jul 12 02:44:09 volumio volumio[1095]: at endReadableNT (node:internal/streams/readable:1376:12) Jul 12 02:44:09 volumio volumio[1095]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jul 12 02:44:09 volumio volumio[1095]: code: 'ECONNRESET', Jul 12 02:44:09 volumio volumio[1095]: path: null, Jul 12 02:44:09 volumio volumio[1095]: host: 'lastfm.freetls.fastly.net', Jul 12 02:44:09 volumio volumio[1095]: port: 443, Jul 12 02:44:09 volumio volumio[1095]: localAddress: undefined Jul 12 02:44:09 volumio volumio[1095]: } Jul 12 02:44:09 volumio volumio[1095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 02:44:10 volumio sudo[13200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-12 02:43' Jul 12 02:44:10 volumio sudo[13200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:28:04 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="fd2f0c5aa414951a829ccaf1ecae9bb1"