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) Jul 12 02:44:10 volumio sudo[13200]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:10 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 789. Jul 12 02:44:10 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:10 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:10 volumio volumio[13212]: Traceback (most recent call last): Jul 12 02:44:10 volumio volumio[13212]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:10 volumio volumio[13212]: from aiohttp import web Jul 12 02:44:10 volumio volumio[13212]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:10 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:10 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:12 volumio volumio-remote-updater[864]: [2025-07-12 02:44:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 12 02:44:12 volumio volumio-remote-updater[864]: [2025-07-12 02:44:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 12 02:44:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:12 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 790. Jul 12 02:44:12 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:12 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:12 volumio volumio[13238]: Traceback (most recent call last): Jul 12 02:44:12 volumio volumio[13238]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:12 volumio volumio[13238]: from aiohttp import web Jul 12 02:44:12 volumio volumio[13238]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:12 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:12 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 12 02:44:13 volumio systemd[1]: volumio.service: Consumed 1min 54.471s CPU time. Jul 12 02:44:13 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jul 12 02:44:13 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jul 12 02:44:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jul 12 02:44:13 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jul 12 02:44:13 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jul 12 02:44:13 volumio systemd[1]: volumio.service: Consumed 1min 54.471s CPU time. Jul 12 02:44:13 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jul 12 02:44:13 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jul 12 02:44:13 volumio volumio[13247]: info: ------------------------------------------- Jul 12 02:44:13 volumio volumio[13247]: info: ----- Volumio3 ---- Jul 12 02:44:13 volumio volumio[13247]: info: ------------------------------------------- Jul 12 02:44:13 volumio volumio[13247]: info: ----- System startup ---- Jul 12 02:44:13 volumio volumio[13247]: info: ------------------------------------------- Jul 12 02:44:13 volumio volumio[13247]: info: MYVOLUMIO Environment detected Jul 12 02:44:14 volumio volumio[13247]: info: Plugin folders cleanup Jul 12 02:44:14 volumio volumio[13247]: info: Scanning into folder /volumio/app/plugins/ Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category audio_interface Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category miscellanea Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category music_service Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category plugins.json Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category system_controller Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category user_interface Jul 12 02:44:14 volumio volumio[13247]: info: Scanning into folder /data/plugins/ Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category audio_interface Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category system_controller Jul 12 02:44:14 volumio volumio[13247]: info: Scanning category user_interface Jul 12 02:44:14 volumio volumio[13247]: info: Plugin folders cleanup completed Jul 12 02:44:14 volumio volumio[13247]: info: ------------------------------------------- Jul 12 02:44:14 volumio volumio[13247]: info: ----- Core plugins startup ---- Jul 12 02:44:14 volumio volumio[13247]: info: ------------------------------------------- Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugins from folder /volumio/app/plugins/ Jul 12 02:44:14 volumio volumio[13247]: info: Adding plugin upnp to MyMusic Plugins Jul 12 02:44:14 volumio volumio[13247]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 12 02:44:14 volumio volumio[13247]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugins from folder /data/plugins/ Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "system"... Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "appearance"... Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "network"... Jul 12 02:44:14 volumio volumio[13247]: info: Refreshing Cached IP Addresses Jul 12 02:44:14 volumio sudo[13276]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 12 02:44:14 volumio sudo[13276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:14 volumio sudo[13278]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 02:44:14 volumio sudo[13278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "services"... Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "alsa_controller"... Jul 12 02:44:14 volumio sudo[13276]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:14 volumio sudo[13285]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 12 02:44:14 volumio sudo[13285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:14 volumio sudo[13278]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:14 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "wizard"... Jul 12 02:44:14 volumio sudo[13285]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "networkfs"... Jul 12 02:44:14 volumio volumio[13247]: info: Starting Udev Watcher for removable devices Jul 12 02:44:14 volumio volumio[13247]: info: Ignoring mount for partition: boot Jul 12 02:44:14 volumio volumio[13247]: info: Ignoring mount for partition: volumio Jul 12 02:44:14 volumio volumio[13247]: info: Ignoring mount for partition: volumio_data Jul 12 02:44:14 volumio volumio[13247]: info: Ignoring mount for partition: 8681-4968 Jul 12 02:44:14 volumio volumio[13247]: info: Ignoring mount for partition: 918d12b7-0ace-4ac3-9a4a-a3e01a26afde Jul 12 02:44:14 volumio volumio[13247]: info: Ignoring mount for partition: 21CC-5DD5 Jul 12 02:44:14 volumio volumio[13247]: info: Ignoring mount for partition: 500g Jul 12 02:44:14 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "volumio_command_line_client"... Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "upnp"... Jul 12 02:44:14 volumio volumio[13247]: info: [1752288254440] Starting Upmpd Daemon Jul 12 02:44:14 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "my_music"... Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "mpd"... Jul 12 02:44:14 volumio volumio[13247]: info: Plugin upnp_browser is not enabled Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "alarm-clock"... Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "airplay_emulation"... Jul 12 02:44:14 volumio volumio[13247]: info: Starting Shairport Sync Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "last_100"... Jul 12 02:44:14 volumio volumio[13247]: info: Loading plugin "webradio"... Jul 12 02:44:14 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 791. Jul 12 02:44:14 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:14 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:14 volumio volumio[13327]: Traceback (most recent call last): Jul 12 02:44:14 volumio volumio[13327]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:14 volumio volumio[13327]: from aiohttp import web Jul 12 02:44:14 volumio volumio[13327]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:14 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:14 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:15 volumio volumio[13247]: info: Loading plugin "i2s_dacs"... Jul 12 02:44:15 volumio volumio[13247]: info: I2S DAC not set, start Auto-detection Jul 12 02:44:15 volumio volumio[13247]: info: Loading plugin "volumiodiscovery"... Jul 12 02:44:15 volumio volumio[13247]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 12 02:44:15 volumio volumio[13247]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 12 02:44:15 volumio volumio[13247]: *** WARNING *** For more information see Jul 12 02:44:15 volumio volumio[13247]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 12 02:44:15 volumio volumio[13247]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 12 02:44:15 volumio volumio[13247]: *** WARNING *** For more information see Jul 12 02:44:15 volumio node[13247]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 12 02:44:15 volumio node[13247]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 12 02:44:15 volumio node[13247]: *** WARNING *** For more information see Jul 12 02:44:15 volumio node[13247]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 12 02:44:15 volumio node[13247]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 12 02:44:15 volumio node[13247]: *** WARNING *** For more information see Jul 12 02:44:15 volumio volumio[13247]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 12 02:44:16 volumio volumio[13247]: info: Discovery: Started advertising with name: Volumio Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "autostart"... Jul 12 02:44:16 volumio volumio[13247]: info: Applying required configuration parameters for plugin autostart Jul 12 02:44:16 volumio volumio[13247]: info: AutoStart - onVolumioStart - read config.json Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "outputs"... Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "albumart"... Jul 12 02:44:16 volumio volumio[13247]: info: Plugin example_plugin is not enabled Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "inputs"... Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "updater_comm"... Jul 12 02:44:16 volumio volumio[13247]: info: Plugin mpdemulation is not enabled Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "rest_api"... Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "websocket"... Jul 12 02:44:16 volumio volumio[13247]: info: Starting Socket.io Server version 1.7.4 Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "fusiondsp"... Jul 12 02:44:16 volumio volumio[13332]: Forking 3 albumart workers Jul 12 02:44:16 volumio volumio[13247]: info: Applying required configuration parameters for plugin fusiondsp Jul 12 02:44:16 volumio volumio[13247]: info: Loading plugin "randomizer"... Jul 12 02:44:16 volumio volumio[13247]: info: Applying required configuration parameters for plugin randomizer Jul 12 02:44:16 volumio volumio[13342]: Starting albumart workers Jul 12 02:44:16 volumio volumio[13344]: Starting albumart workers Jul 12 02:44:16 volumio volumio[13247]: info: Loading i18n strings for locale zh Jul 12 02:44:16 volumio volumio[13247]: Updating browse sources language Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 02:44:16 volumio volumio[13343]: Starting albumart workers Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::initPlayerControls Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 02:44:16 volumio volumio[13247]: Express server listening on port 3000 Jul 12 02:44:16 volumio volumio[13247]: [Metrics] WebUI: 3s 372.92ms Jul 12 02:44:17 volumio volumio[13247]: info: Setting Device type: x86 Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::resetVolumioState Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::getcurrentVolume Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 12 02:44:17 volumio volumio[13247]: info: Completed loading Core Plugins Jul 12 02:44:17 volumio volumio[13247]: info: Preparing to generate the ALSA configuration file Jul 12 02:44:17 volumio volumio[13247]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 12 02:44:17 volumio volumio[13247]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 12 02:44:17 volumio volumio[13247]: info: Reading ALSA contributions from plugins. Jul 12 02:44:17 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 792. Jul 12 02:44:17 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:17 volumio volumio[13247]: info: Volumio Network Manager: Network status updated: 1 Jul 12 02:44:17 volumio volumio[13247]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 12 02:44:17 volumio volumio[13247]: wlan0 Interface doesn't support scanning. Jul 12 02:44:17 volumio volumio[13247]: info: Cannot use regular scanning, forcing with ap-force Jul 12 02:44:17 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:17 volumio sudo[13381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 12 02:44:17 volumio sudo[13381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio sudo[13381]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio volumio[13247]: command failed: No such device (-19) Jul 12 02:44:17 volumio volumio[13247]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jul 12 02:44:17 volumio volumio[13247]: command failed: No such device (-19) Jul 12 02:44:17 volumio volumio[13247]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 12 02:44:17 volumio volumio[13384]: Traceback (most recent call last): Jul 12 02:44:17 volumio volumio[13384]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:17 volumio volumio[13384]: from aiohttp import web Jul 12 02:44:17 volumio volumio[13384]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:17 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:17 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:17 volumio volumio[13247]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:17 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::updateTrackBlock Jul 12 02:44:17 volumio volumio[13247]: info: CorePlayQueue::getTrackBlock Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioRetrievevolume Jul 12 02:44:17 volumio volumio[13247]: info: Reloading queue from file Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::setRepeat null single undefined Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:17 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::setRandom true Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:17 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:17 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:17 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:17 volumio volumio[13247]: info: Asound.conf file unchanged, so no further update is needed Jul 12 02:44:17 volumio volumio[13247]: info: Output device has changed, restarting MPD Jul 12 02:44:17 volumio volumio[13247]: info: Output device has changed, restarting Shairport Sync Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio sudo[13417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 02:44:17 volumio sudo[13417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio sudo[13417]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio volumio[13247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 02:44:17 volumio volumio[13247]: info: ___________ START PLUGINS ___________ Jul 12 02:44:17 volumio sudo[13420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 02:44:17 volumio volumio[13247]: info: ControllerMpd::onStart: Initializing MPD Jul 12 02:44:17 volumio sudo[13420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio volumio[13247]: info: Creating MPD Configuration file Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio sudo[13429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 02:44:17 volumio sudo[13429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 12 02:44:17 volumio volumio[13247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio sudo[13429]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio sudo[13430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 02:44:17 volumio sudo[13430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio volumio[13247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 12 02:44:17 volumio volumio[13247]: info: [1752288257331] CoreMusicLibrary::Adding element Last_100 Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 12 02:44:17 volumio volumio[13247]: info: [1752288257332] CoreMusicLibrary::Adding element Webradio Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 12 02:44:17 volumio volumio[13247]: info: Initializing BBC Radios Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: AutoStart - onStart Jul 12 02:44:17 volumio volumio[13247]: info: Loading i18n strings for locale zh Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: FusionDsp - mixtype--------------------- Software Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 12 02:44:17 volumio volumio[13247]: info: [1752288257398] CoreMusicLibrary::Adding element Randomizer Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 02:44:17 volumio volumio[13247]: Cannot find translation for source Randomizer Jul 12 02:44:17 volumio volumio[13247]: info: Volumio Calling Home Jul 12 02:44:17 volumio volumio[13247]: info: Preparing to generate the ALSA configuration file Jul 12 02:44:17 volumio volumio[13247]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 12 02:44:17 volumio volumio[13247]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 12 02:44:17 volumio volumio[13247]: info: Reading ALSA contributions from plugins. Jul 12 02:44:17 volumio volumio[13247]: info: MPD Permissions set Jul 12 02:44:17 volumio volumio[13247]: info: MPD Permissions set Jul 12 02:44:17 volumio volumio[13247]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 12 02:44:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 12 02:44:17 volumio systemd[1]: mpd.service: Consumed 27.365s CPU time. Jul 12 02:44:17 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 12 02:44:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 12 02:44:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 12 02:44:17 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 12 02:44:17 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 12 02:44:17 volumio volumio[13247]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 12 02:44:17 volumio volumio[13247]: info: Discovery: adding b55ae850-5da2-4f4a-b72a-574e1a0a09ce Jul 12 02:44:17 volumio volumio[13247]: info: Discovery: Found device Volumio Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:17 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:17 volumio volumio[13247]: info: Starting Shairport Sync Jul 12 02:44:17 volumio volumio[13247]: info: Starting Shairport Sync Jul 12 02:44:17 volumio volumio[13247]: info: Starting Shairport Sync Jul 12 02:44:17 volumio sudo[13478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 02:44:17 volumio sudo[13478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio volumio[13247]: info: Discovery: this is already registered, b55ae850-5da2-4f4a-b72a-574e1a0a09ce Jul 12 02:44:17 volumio volumio[13247]: info: Discovery: Found device Volumio Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:17 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:17 volumio sudo[13480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 02:44:17 volumio sudo[13480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio volumio-remote-updater[864]: [2025-07-12 02:44:17] [connect] Successful connection Jul 12 02:44:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 12 02:44:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 12 02:44:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 12 02:44:17 volumio systemd[1]: shairport-sync.service: Consumed 12.404s CPU time. Jul 12 02:44:17 volumio volumio[13247]: info: Asound.conf file unchanged, so no further update is needed Jul 12 02:44:17 volumio volumio[13247]: info: Output device has changed, restarting MPD Jul 12 02:44:17 volumio sudo[13483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 02:44:17 volumio sudo[13483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio sudo[13476]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 12 02:44:17 volumio sudo[13476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 12 02:44:17 volumio sudo[13476]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio volumio[13247]: info: Output device has changed, restarting Shairport Sync Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio sudo[13489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 02:44:17 volumio sudo[13489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 12 02:44:17 volumio sudo[13492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 02:44:17 volumio sudo[13492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio sudo[13489]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio sudo[13478]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 12 02:44:17 volumio volumio[13247]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 02:44:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 12 02:44:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 12 02:44:17 volumio volumio[13247]: info: MPD Permissions set Jul 12 02:44:17 volumio volumio[13247]: info: Shairport-Sync Started Jul 12 02:44:17 volumio volumio[13247]: Error adding Membership: Error: addMembership EINVAL Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 12 02:44:17 volumio sudo[13483]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 12 02:44:17 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 12 02:44:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 12 02:44:17 volumio sudo[13480]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 12 02:44:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 12 02:44:17 volumio volumio-remote-updater[864]: [2025-07-12 02:44:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1752288257 101 Jul 12 02:44:17 volumio volumio[13247]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Jul 12 02:44:17 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 12 02:44:17 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 12 02:44:17 volumio volumio[13247]: info: Shairport-Sync Started Jul 12 02:44:17 volumio volumio[13247]: info: Shairport-Sync Started Jul 12 02:44:17 volumio volumio[13247]: info: Starting Shairport Sync Jul 12 02:44:17 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:17 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:17 volumio sudo[13531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 02:44:17 volumio sudo[13531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 12 02:44:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 12 02:44:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 12 02:44:17 volumio sudo[13515]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 12 02:44:17 volumio sudo[13515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 12 02:44:17 volumio sudo[13515]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 12 02:44:17 volumio sudo[13531]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:17 volumio volumio[13247]: info: Shairport-Sync Started Jul 12 02:44:18 volumio volumio[13247]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:18 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetVisibleSources Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 12 02:44:18 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:18 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: Listing playlists Jul 12 02:44:18 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:18 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetQueue Jul 12 02:44:18 volumio volumio[13247]: info: CoreStateMachine::getQueue Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getQueue Jul 12 02:44:18 volumio mpd[13535]: 2025-07-12T02:44:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetVisibleSources Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 12 02:44:18 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:18 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: Listing playlists Jul 12 02:44:18 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:18 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetQueue Jul 12 02:44:18 volumio volumio[13247]: info: CoreStateMachine::getQueue Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getQueue Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetVisibleSources Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 12 02:44:18 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:18 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:18 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: Listing playlists Jul 12 02:44:18 volumio sudo[13492]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:18 volumio sudo[13430]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:18 volumio sudo[13420]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:18 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:18 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::volumioGetQueue Jul 12 02:44:18 volumio volumio[13247]: info: CoreStateMachine::getQueue Jul 12 02:44:18 volumio volumio[13247]: info: CorePlayQueue::getQueue Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: Completed starting Core Plugins Jul 12 02:44:18 volumio volumio[13247]: info: ------------------------------------------- Jul 12 02:44:18 volumio volumio[13247]: info: ----- MyVolumio plugins startup ---- Jul 12 02:44:18 volumio volumio[13247]: info: ------------------------------------------- Jul 12 02:44:18 volumio volumio[13247]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:18 volumio volumio[13247]: error: MPD error: The expression evaluated to a falsy value: Jul 12 02:44:18 volumio volumio[13247]: assert.ok(self.idling) Jul 12 02:44:18 volumio volumio[13247]: error: The expression evaluated to a falsy value: Jul 12 02:44:18 volumio volumio[13247]: assert.ok(self.idling) Jul 12 02:44:18 volumio volumio[13247]: error: MPD error: The expression evaluated to a falsy value: Jul 12 02:44:18 volumio volumio[13247]: assert.ok(self.idling) Jul 12 02:44:18 volumio volumio[13247]: error: The expression evaluated to a falsy value: Jul 12 02:44:18 volumio volumio[13247]: assert.ok(self.idling) Jul 12 02:44:18 volumio volumio[13247]: info: MPD running with PID13535 Jul 12 02:44:18 volumio volumio[13247]: ,establishing connection Jul 12 02:44:18 volumio volumio[13247]: error: updateQueue error: null Jul 12 02:44:19 volumio volumio[13247]: error: updateQueue error: null Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 793. Jul 12 02:44:19 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:19 volumio volumio[13559]: Traceback (most recent call last): Jul 12 02:44:19 volumio volumio[13559]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:19 volumio volumio[13559]: from aiohttp import web Jul 12 02:44:19 volumio volumio[13559]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:19 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:19 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:19 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 12 02:44:20 volumio volumio[13247]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 12 02:44:20 volumio volumio[13247]: info: camilladsp service started and running in background, instance 1 Jul 12 02:44:20 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:20 volumio volumio[13247]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 <-AAAAAAAAAAAAA Jul 12 02:44:20 volumio volumio[13247]: info: FusionDsp loaded Jul 12 02:44:20 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:20 volumio volumio[13247]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 12 02:44:20 volumio volumio[13247]: info: Adding Signal Path Element [object Object] Jul 12 02:44:20 volumio volumio[13247]: info: Adding fusiondspeq DSP Signal Path Element Jul 12 02:44:20 volumio volumio[13247]: info: FusionDsp - ---- installed callbackRead Jul 12 02:44:20 volumio volumio[13247]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 02:44:20 volumio sudo[13575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 12 02:44:20 volumio sudo[13575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:20 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:21 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 794. Jul 12 02:44:21 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:21 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:21 volumio sudo[13575]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:21 volumio volumio[13580]: Traceback (most recent call last): Jul 12 02:44:21 volumio volumio[13580]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in Jul 12 02:44:21 volumio volumio[13580]: from aiohttp import web Jul 12 02:44:21 volumio volumio[13580]: ModuleNotFoundError: No module named 'aiohttp' Jul 12 02:44:21 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jul 12 02:44:21 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:22 volumio volumio[13247]: error: FusionDsp - Reload WebSocket error: [object Object] Jul 12 02:44:23 volumio systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 795. Jul 12 02:44:23 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:23 volumio systemd[1]: fusiondsp.service: Start request repeated too quickly. Jul 12 02:44:23 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 12 02:44:23 volumio systemd[1]: Failed to start fusiondsp.service - FusionDsp Daemon. Jul 12 02:44:24 volumio volumio[13247]: Cannot compose Albumart path Jul 12 02:44:24 volumio volumio[13247]: Cannot compose Albumart path Jul 12 02:44:24 volumio volumio[13247]: Cannot compose Albumart path Jul 12 02:44:24 volumio sudo[13603]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 12 02:44:24 volumio sudo[13603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:24 volumio sudo[13603]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:24 volumio sudo[13605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 02:44:24 volumio sudo[13605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:24 volumio sudo[13608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 12 02:44:24 volumio sudo[13608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:24 volumio sudo[13605]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:24 volumio sudo[13608]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:24 volumio volumio[13247]: info: Upmpdcli Daemon Started Jul 12 02:44:32 volumio volumio[13247]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=4.015&uuid=1c860a6758a5b10c279361dbd17f4b77" http://updates.volumio.org/downloader-v1/track-device Jul 12 02:44:32 volumio volumio[13247]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 12 02:44:32 volumio volumio[13247]: Dload Upload Total Spent Left Speed Jul 12 02:44:32 volumio volumio[13247]: [316B blob data] Jul 12 02:44:32 volumio volumio[13247]: curl: (52) Empty reply from server Jul 12 02:44:32 volumio volumio[13247]: retrying in 5 seconds, trial 0 Jul 12 02:44:32 volumio volumio[13247]: info: Volumio Calling Home Jul 12 02:44:37 volumio sudo[13643]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 12 02:44:37 volumio sudo[13643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:37 volumio sudo[13643]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:37 volumio sudo[13645]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 02:44:37 volumio sudo[13645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:37 volumio sudo[13645]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:37 volumio volumio[13247]: verbose: New Socket.io Connection to 192.168.5.242 from 192.168.5.163 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 12 02:44:37 volumio volumio[13247]: info: AutoStart - Plugin is starting Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioGetQueue Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::getQueue Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getQueue Jul 12 02:44:37 volumio volumio[13247]: info: AutoStart - start playing Jul 12 02:44:37 volumio volumio[13247]: info: AutoStart - start playing with no specific position Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPlay Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::play index 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::stop Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::play index undefined Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::startPlaybackTimer Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3 Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand stop Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand stop took 1 milliseconds Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand clear Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand clear took 1 milliseconds Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3" Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces system playlist update Jul 12 02:44:37 volumio volumio[13247]: info: Ignoring MPD Status Update Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces system playlist update Jul 12 02:44:37 volumio volumio[13247]: info: Ignoring MPD Status Update Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand add "USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3" took 2 milliseconds Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand play Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces system playlist update Jul 12 02:44:37 volumio volumio[13247]: info: Ignoring MPD Status Update Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces system playlist update Jul 12 02:44:37 volumio volumio[13247]: info: Ignoring MPD Status Update Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces system playlist update Jul 12 02:44:37 volumio volumio[13247]: info: Ignoring MPD Status Update Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 5ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 5ms Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand play took 4 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 3ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 3ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 3ms Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces system playlist update Jul 12 02:44:37 volumio volumio[13247]: info: Ignoring MPD Status Update Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces system playlist update Jul 12 02:44:37 volumio volumio[13247]: info: Ignoring MPD Status Update Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces system playlist update Jul 12 02:44:37 volumio volumio[13247]: info: Ignoring MPD Status Update Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 2ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 2ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 1ms Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces state update: player Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::getState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces state update: player Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::getState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces state update: player Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::getState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces state update: player Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::getState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 12 02:44:37 volumio volumio[13247]: info: camilladsp stopping service pid 13568... Jul 12 02:44:37 volumio volumio[13247]: info: camilladsp service terminated, instance 1 Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 02:44:37 volumio volumio[13247]: info: camilladsp service started and running in background, instance 1 Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces state update: player Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand status took 22 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::getState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand status took 20 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand status took 19 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand status took 18 milliseconds Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces state update: player Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::getState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces state update: player Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::getState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:37 volumio volumio[13247]: info: Jul 12 02:44:37 volumio volumio[13247]: ---------------------------- MPD announces state update: player Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::getState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand status Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand status took 13 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand status took 10 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand status took 10 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand status took 10 milliseconds Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseState Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::servicePushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loving You","artist":"程璧","album":null,"uri":"USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3","trackType":"mp3"} Jul 12 02:44:37 volumio volumio[13247]: verbose: CURRENT POSITION 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState currentStatus stop Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::servicePushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loving You","artist":"程璧","album":null,"uri":"USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3","trackType":"mp3"} Jul 12 02:44:37 volumio volumio[13247]: verbose: CURRENT POSITION 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:37 volumio volumio[13247]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::servicePushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loving You","artist":"程璧","album":null,"uri":"USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3","trackType":"mp3"} Jul 12 02:44:37 volumio volumio[13247]: verbose: CURRENT POSITION 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:37 volumio volumio[13247]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::servicePushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loving You","artist":"程璧","album":null,"uri":"USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3","trackType":"mp3"} Jul 12 02:44:37 volumio volumio[13247]: verbose: CURRENT POSITION 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:37 volumio volumio[13247]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 48ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 58ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 56ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 56ms Jul 12 02:44:37 volumio volumio[13247]: error: FusionDsp - Reload WebSocket error: [object Object] Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 12 02:44:37 volumio volumio[13247]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:37 volumio volumio[13247]: verbose: ControllerMpd::parseTrackInfo Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::servicePushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loving You","artist":"程璧","album":null,"uri":"USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3","trackType":"mp3"} Jul 12 02:44:37 volumio volumio[13247]: verbose: CURRENT POSITION 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:37 volumio volumio[13247]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::servicePushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loving You","artist":"程璧","album":null,"uri":"USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3","trackType":"mp3"} Jul 12 02:44:37 volumio volumio[13247]: verbose: CURRENT POSITION 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:37 volumio volumio[13247]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::servicePushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loving You","artist":"程璧","album":null,"uri":"USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3","trackType":"mp3"} Jul 12 02:44:37 volumio volumio[13247]: verbose: CURRENT POSITION 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:37 volumio volumio[13247]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: ControllerMpd::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::servicePushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Loving You","artist":"程璧","album":null,"uri":"USB/500g/mp3/网易云音乐-MP3/程璧 - Loving You.mp3","trackType":"mp3"} Jul 12 02:44:37 volumio volumio[13247]: verbose: CURRENT POSITION 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState stateService play Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::syncState currentStatus play Jul 12 02:44:37 volumio volumio[13247]: info: Received an update from plugin. extracting info from payload Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: CoreStateMachine::pushState Jul 12 02:44:37 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::volumioPushState Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 64ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 60ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 60ms Jul 12 02:44:37 volumio volumio[13247]: info: ------------------------------ 60ms Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Volumio is playing Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio volumio[13247]: info: FusionDsp - Clipping Monitor started Jul 12 02:44:37 volumio sudo[13691]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 12 02:44:37 volumio sudo[13691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:37 volumio sudo[13691]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:37 volumio sudo[13694]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 12 02:44:37 volumio sudo[13694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 12 02:44:37 volumio sudo[13694]: pam_unix(sudo:session): session closed for user root Jul 12 02:44:37 volumio volumio[13247]: verbose: New Socket.io Connection to 192.168.5.242 from 192.168.5.163 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 12 02:44:37 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 12 02:44:37 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::volumioGetVisibleSources Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:38 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 12 02:44:38 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:38 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:38 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:38 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:38 volumio volumio[13247]: info: Listing playlists Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 02:44:38 volumio volumio[13247]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:38 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:38 volumio volumio[13247]: error: MyVolumio Plugin failed to start in a timely fashion Jul 12 02:44:38 volumio volumio[13247]: [Metrics] CommandRouter: 25s 40.79ms Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::volumiosetStartupVolume Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::Close All Modals sent Jul 12 02:44:38 volumio volumio[13247]: info: CoreCommandRouter::Close All Modals sent Jul 12 02:44:38 volumio volumio[13247]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 12 02:44:38 volumio volumio[13247]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 12 02:44:38 volumio volumio[13247]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 12 02:44:39 volumio volumio[13247]: info: Received Get System Info Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 12 02:44:39 volumio volumio[13247]: info: Discovery: Getting this device information Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::volumioGetState Jul 12 02:44:39 volumio volumio[13247]: info: CorePlayQueue::getTrack 0 Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 02:44:39 volumio volumio[13247]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 12 02:44:41 volumio volumio[13247]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 02:44:41 volumio volumio[13247]: Error: socket hang up Jul 12 02:44:41 volumio volumio[13247]: at connResetException (node:internal/errors:720:14) Jul 12 02:44:41 volumio volumio[13247]: at Socket.socketOnEnd (node:_http_client:519:23) Jul 12 02:44:41 volumio volumio[13247]: at Socket.emit (node:events:526:35) Jul 12 02:44:41 volumio volumio[13247]: at endReadableNT (node:internal/streams/readable:1376:12) Jul 12 02:44:41 volumio volumio[13247]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jul 12 02:44:41 volumio volumio[13247]: code: 'ECONNRESET' Jul 12 02:44:41 volumio volumio[13247]: } Jul 12 02:44:41 volumio volumio[13247]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 02:44:41 volumio sudo[13733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-12 02:43' Jul 12 02:44:41 volumio sudo[13733]: 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"