-- Logs begin at Thu 2025-05-08 20:51:42 CEST, end at Thu 2025-05-08 21:40:25 CEST. -- May 08 21:39:00 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.106 UA: Mozilla/5.0 (Linux; Android 10; ELE-L29 Build/HUAWEIELE-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::volumioGetVisibleSources May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:00 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 21:39:00 volumio volumio[982]: info: Received Get System Info May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:39:00 volumio volumio[982]: info: Discovery: Getting this device information May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:00 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:00 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:00 volumio volumio[982]: info: Listing playlists May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::volumioGetQueue May 08 21:39:00 volumio volumio[982]: info: CoreStateMachine::getQueue May 08 21:39:00 volumio volumio[982]: info: CorePlayQueue::getQueue May 08 21:39:00 volumio sudo[12786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 08 21:39:00 volumio sudo[12786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:39:00 volumio volumio[982]: info: creating filters folder and copying demo filters May 08 21:39:00 volumio volumio[982]: info: copying demo flters May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 08 21:39:00 volumio volumio[982]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 08 21:39:00 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:39:03 volumio volumio-remote-updater[615]: [2025-05-08 21:39:03] [connect] Successful connection May 08 21:39:03 volumio volumio[982]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 May 08 21:39:03 volumio volumio-remote-updater[615]: [2025-05-08 21:39:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746733143 101 May 08 21:39:05 volumio volumio[982]: info: CoreCommandRouter::volumioPause May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::pause May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::servicePause May 08 21:39:05 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:05 volumio volumio[982]: info: CoreCommandRouter::servicePause May 08 21:39:05 volumio volumio[982]: info: ControllerMpd::pause May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand pause May 08 21:39:05 volumio volumio[982]: info: May 08 21:39:05 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:05 volumio volumio[982]: info: sendMpdCommand pause took 2 milliseconds May 08 21:39:05 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:05 volumio volumio[982]: info: May 08 21:39:05 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:05 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:05 volumio volumio[982]: info: sendMpdCommand status took 2 milliseconds May 08 21:39:05 volumio volumio[982]: info: sendMpdCommand status took 2 milliseconds May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:05 volumio volumio[982]: info: sendMpdCommand playlistinfo took 1 milliseconds May 08 21:39:05 volumio volumio[982]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:05 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:05 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:05 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:05 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:05 volumio volumio[982]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26952,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"816 Kbps","isStreaming":false,"title":"I nic i nic","artist":"Andrzej Sikorowski, Maja Sikorowska","album":"Krakow-Saloniki","uri":"USB/RASPBERRY/Piosenki/Polskie/Sikorowski Sikorowska Kraków Saloniki/10 I nic i nic.flac","trackType":"flac"} May 08 21:39:05 volumio volumio[982]: verbose: CURRENT POSITION 577 May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::syncState stateService pause May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus pause May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:05 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:05 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:05 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 21:39:05 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:05 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:05 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:05 volumio volumio[982]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26952,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"816 Kbps","isStreaming":false,"title":"I nic i nic","artist":"Andrzej Sikorowski, Maja Sikorowska","album":"Krakow-Saloniki","uri":"USB/RASPBERRY/Piosenki/Polskie/Sikorowski Sikorowska Kraków Saloniki/10 I nic i nic.flac","trackType":"flac"} May 08 21:39:05 volumio volumio[982]: verbose: CURRENT POSITION 577 May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::syncState stateService pause May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus pause May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:05 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:05 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:05 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 21:39:05 volumio volumio[982]: info: ------------------------------ 42ms May 08 21:39:05 volumio volumio[982]: info: ------------------------------ 40ms May 08 21:39:07 volumio volumio[982]: info: ./presets/ May 08 21:39:07 volumio volumio[982]: info: ./presets/2XEQ15/ May 08 21:39:07 volumio volumio[982]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 08 21:39:07 volumio sudo[12827]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 08 21:39:07 volumio sudo[12827]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:39:07 volumio volumio[982]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: ./presets/PEQ/ May 08 21:39:07 volumio volumio[982]: info: ./presets/convfir/ May 08 21:39:07 volumio volumio[982]: info: ./presets/convfir/kef.json May 08 21:39:07 volumio volumio[982]: info: ./presets/convfir/test.json May 08 21:39:07 volumio volumio[982]: info: ./presets/EQ15/ May 08 21:39:07 volumio volumio[982]: info: ./presets/EQ15/.Bass-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: ./presets/EQ15/.Classic-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: ./presets/EQ15/.Voice-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: ./presets/EQ15/.Rock-FusionDsp.json May 08 21:39:07 volumio volumio[982]: info: Installing/fusiondsp dependencies May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPlay May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::play index 1 May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::stop May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 577 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::stop May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand stop took 14 milliseconds May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/RASPBERRY/Piosenki/Filmowa/- Dom.flac May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand status took 6 milliseconds May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand stop took 4 milliseconds May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:07 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand playlistinfo took 5 milliseconds May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand clear took 6 milliseconds May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand status took 3 milliseconds May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/RASPBERRY/Piosenki/Filmowa/- Dom.flac" May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: 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":"I nic i nic","artist":"Andrzej Sikorowski, Maja Sikorowska","album":"Krakow-Saloniki","uri":"USB/RASPBERRY/Piosenki/Polskie/Sikorowski Sikorowska Kraków Saloniki/10 I nic i nic.flac","trackType":"flac"} May 08 21:39:07 volumio volumio[982]: verbose: CURRENT POSITION 1 May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: No code May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:39:07 volumio volumio[982]: verbose: CURRENT POSITION 1 May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: No code May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: ------------------------------ 104ms May 08 21:39:07 volumio volumio[982]: info: ------------------------------ 95ms May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:07 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:07 volumio volumio[982]: error: updateQueue error: null May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:07 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:07 volumio volumio[982]: info: ------------------------------ 101ms May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand add "USB/RASPBERRY/Piosenki/Filmowa/- Dom.flac" took 99 milliseconds May 08 21:39:07 volumio volumio[982]: info: ------------------------------ 8ms May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:07 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:07 volumio volumio[982]: info: ------------------------------ 21ms May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand play took 20 milliseconds May 08 21:39:07 volumio volumio[982]: info: ------------------------------ 19ms May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:07 volumio volumio[982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 08 21:39:07 volumio volumio[982]: info: FusionDsp - ---- read samplerate from file: 44100 May 08 21:39:07 volumio volumio[982]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 08 21:39:07 volumio volumio[982]: info: FusionDsp - else 1 -2.5 May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:07 volumio volumio[982]: info: May 08 21:39:07 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand status took 23 milliseconds May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand status took 21 milliseconds May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand status took 11 milliseconds May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand status took 9 milliseconds May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand playlistinfo took 7 milliseconds May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand playlistinfo took 7 milliseconds May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dom","artist":null,"album":"Melodie z ulubionych seriali","uri":"USB/RASPBERRY/Piosenki/Filmowa/- Dom.flac","trackType":"flac"} May 08 21:39:07 volumio volumio[982]: verbose: CURRENT POSITION 1 May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dom","artist":null,"album":"Melodie z ulubionych seriali","uri":"USB/RASPBERRY/Piosenki/Filmowa/- Dom.flac","trackType":"flac"} May 08 21:39:07 volumio volumio[982]: verbose: CURRENT POSITION 1 May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:07 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: ------------------------------ 56ms May 08 21:39:07 volumio volumio[982]: info: ------------------------------ 76ms May 08 21:39:07 volumio volumio[982]: error: FusionDsp - WebSocket error: [object Object] May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand playlistinfo took 45 milliseconds May 08 21:39:07 volumio volumio[982]: info: sendMpdCommand playlistinfo took 45 milliseconds May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:07 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"452 Kbps","isStreaming":false,"title":"Dom","artist":null,"album":"Melodie z ulubionych seriali","uri":"USB/RASPBERRY/Piosenki/Filmowa/- Dom.flac","trackType":"flac"} May 08 21:39:07 volumio volumio[982]: verbose: CURRENT POSITION 1 May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:07 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":117,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"452 Kbps","isStreaming":false,"title":"Dom","artist":null,"album":"Melodie z ulubionych seriali","uri":"USB/RASPBERRY/Piosenki/Filmowa/- Dom.flac","trackType":"flac"} May 08 21:39:07 volumio volumio[982]: verbose: CURRENT POSITION 1 May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:07 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:07 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:07 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:07 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:08 volumio volumio[982]: info: ------------------------------ 129ms May 08 21:39:08 volumio volumio[982]: info: ------------------------------ 127ms May 08 21:39:08 volumio volumio[982]: info: Get:1 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] May 08 21:39:08 volumio volumio[982]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease May 08 21:39:08 volumio volumio[982]: info: Get:3 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] May 08 21:39:10 volumio volumio[982]: info: Get:4 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] May 08 21:39:11 volumio volumio[982]: info: Fetched 469 kB in 3s (149 kB/s) May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioNext May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::next May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::stop May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 1 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::stop May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand stop took 12 milliseconds May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Z Rozmów.flac May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand status took 9 milliseconds May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand stop took 4 milliseconds May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:14 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand playlistinfo took 11 milliseconds May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand clear took 11 milliseconds May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand status took 6 milliseconds May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Z Rozmów.flac" May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: 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":"Dom","artist":null,"album":"Melodie z ulubionych seriali","uri":"USB/RASPBERRY/Piosenki/Filmowa/- Dom.flac","trackType":"flac"} May 08 21:39:14 volumio volumio[982]: verbose: CURRENT POSITION 555 May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: No code May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:39:14 volumio volumio[982]: verbose: CURRENT POSITION 555 May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: No code May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 129ms May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 114ms May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:14 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:14 volumio volumio[982]: error: updateQueue error: null May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:14 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 114ms May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand add "USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Z Rozmów.flac" took 109 milliseconds May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 6ms May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:14 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 21ms May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand play took 17 milliseconds May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 17ms May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand status took 10 milliseconds May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand status took 9 milliseconds May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:14 volumio volumio[982]: info: May 08 21:39:14 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:14 volumio volumio[982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 08 21:39:14 volumio volumio[982]: info: FusionDsp - ---- read samplerate from file: 44100 May 08 21:39:14 volumio volumio[982]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 08 21:39:14 volumio volumio[982]: info: FusionDsp - else 1 -2.5 May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand status took 21 milliseconds May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand playlistinfo took 21 milliseconds May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand playlistinfo took 21 milliseconds May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand status took 18 milliseconds May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Z Rozmów","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Z Rozmów.flac","trackType":"flac"} May 08 21:39:14 volumio volumio[982]: verbose: CURRENT POSITION 555 May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Z Rozmów","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Z Rozmów.flac","trackType":"flac"} May 08 21:39:14 volumio volumio[982]: verbose: CURRENT POSITION 555 May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:14 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 56ms May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 70ms May 08 21:39:14 volumio volumio[982]: error: FusionDsp - WebSocket error: [object Object] May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand playlistinfo took 38 milliseconds May 08 21:39:14 volumio volumio[982]: info: sendMpdCommand playlistinfo took 37 milliseconds May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:14 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Z Rozmów","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Z Rozmów.flac","trackType":"flac"} May 08 21:39:14 volumio volumio[982]: verbose: CURRENT POSITION 555 May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:14 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Z Rozmów","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Z Rozmów.flac","trackType":"flac"} May 08 21:39:14 volumio volumio[982]: verbose: CURRENT POSITION 555 May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:14 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:14 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:14 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 136ms May 08 21:39:14 volumio volumio[982]: info: ------------------------------ 132ms May 08 21:39:16 volumio volumio[982]: info: Reading package lists... May 08 21:39:17 volumio volumio[982]: info: Building dependency tree... May 08 21:39:17 volumio volumio[982]: info: Reading state information... May 08 21:39:17 volumio volumio[982]: info: All packages are up to date. May 08 21:39:17 volumio sudo[12827]: pam_unix(sudo:session): session closed for user root May 08 21:39:17 volumio sudo[13107]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 08 21:39:17 volumio sudo[13107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:39:18 volumio volumio[982]: info: Reading package lists... May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPlay May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::play index 556 May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::stop May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 555 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::stop May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand stop took 11 milliseconds May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand status took 5 milliseconds May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand stop took 3 milliseconds May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand status took 2 milliseconds May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:18 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand clear took 3 milliseconds May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac" May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: 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":"Z Rozmów","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Z Rozmów.flac","trackType":"flac"} May 08 21:39:18 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: No code May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 55ms May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 51ms May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:18 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:18 volumio volumio[982]: error: updateQueue error: null May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:18 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 50ms May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand add "USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac" took 48 milliseconds May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 4ms May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:18 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 16ms May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand play took 14 milliseconds May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 13ms May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:18 volumio volumio[982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 08 21:39:18 volumio volumio[982]: info: FusionDsp - ---- read samplerate from file: 44100 May 08 21:39:18 volumio volumio[982]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 08 21:39:18 volumio volumio[982]: info: FusionDsp - else 1 -2.5 May 08 21:39:18 volumio volumio[982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 08 21:39:18 volumio volumio[982]: info: FusionDsp - ---- read samplerate from file: 44100 May 08 21:39:18 volumio volumio[982]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 08 21:39:18 volumio volumio[982]: info: FusionDsp - else 1 -2.5 May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand status took 149 milliseconds May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand status took 148 milliseconds May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:18 volumio volumio[982]: info: May 08 21:39:18 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand status took 7 milliseconds May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand playlistinfo took 7 milliseconds May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand playlistinfo took 6 milliseconds May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:39:18 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:39:18 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:18 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 183ms May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 196ms May 08 21:39:18 volumio volumio[982]: error: FusionDsp - WebSocket error: [object Object] May 08 21:39:18 volumio volumio[982]: error: FusionDsp - WebSocket error: [object Object] May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand status took 45 milliseconds May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand playlistinfo took 40 milliseconds May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:39:18 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:18 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 87ms May 08 21:39:18 volumio volumio[982]: info: sendMpdCommand playlistinfo took 49 milliseconds May 08 21:39:18 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:18 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:39:18 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:18 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:18 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:18 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:18 volumio volumio[982]: info: ------------------------------ 127ms May 08 21:39:18 volumio volumio[982]: info: Building dependency tree... May 08 21:39:18 volumio volumio[982]: info: Reading state information... May 08 21:39:33 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:39:35 volumio sudo[13107]: pam_unix(sudo:session): session closed for user root May 08 21:39:35 volumio sudo[13181]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 08 21:39:35 volumio sudo[13181]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:39:37 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 08 21:39:38 volumio sudo[13181]: pam_unix(sudo:session): session closed for user root May 08 21:39:38 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:39:38 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:39:38 volumio volumio[982]: info: Getting Alsa Cards List without I2S DAC May 08 21:39:39 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 08 21:39:39 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 08 21:39:40 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 21:39:40 volumio volumio[982]: Upnp client error: Error: This socket has been ended by the other party May 08 21:39:40 volumio volumio[982]: Upnp client error: Error: This socket has been ended by the other party May 08 21:39:40 volumio volumio[982]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). May 08 21:39:40 volumio volumio[982]: info: python3-pip is already the newest version (18.1-5+rpt1). May 08 21:39:40 volumio volumio[982]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. May 08 21:39:40 volumio volumio[982]: info: lib/systemd/system/fusiondsp.service May 08 21:39:47 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:39:47 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:39:47 volumio volumio[982]: info: Discovery: Getting this device information May 08 21:39:47 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:47 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:47 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:39:47 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.0.139:3000 from 192.168.0.106 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 08 21:39:47 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:47 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:47 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 08 21:39:47 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 08 21:39:49 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:49 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:50 volumio sudo[13240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 21:39:50 volumio sudo[13240]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:39:50 volumio sudo[13240]: pam_unix(sudo:session): session closed for user root May 08 21:39:50 volumio sudo[13242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 21:39:50 volumio sudo[13242]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:39:50 volumio sudo[13242]: pam_unix(sudo:session): session closed for user root May 08 21:39:50 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.106 UA: Mozilla/5.0 (Linux; Android 10; ELE-L29 Build/HUAWEIELE-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 08 21:39:50 volumio sudo[13246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 21:39:50 volumio sudo[13246]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:39:50 volumio sudo[13246]: pam_unix(sudo:session): session closed for user root May 08 21:39:50 volumio sudo[13248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 21:39:50 volumio sudo[13248]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:39:50 volumio sudo[13248]: pam_unix(sudo:session): session closed for user root May 08 21:39:50 volumio volumio[982]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.106 UA: Mozilla/5.0 (Linux; Android 10; ELE-L29 Build/HUAWEIELE-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::volumioGetVisibleSources May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:50 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::volumioGetQueue May 08 21:39:50 volumio volumio[982]: info: CoreStateMachine::getQueue May 08 21:39:50 volumio volumio[982]: info: CorePlayQueue::getQueue May 08 21:39:50 volumio volumio[982]: info: Listing playlists May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 21:39:50 volumio volumio[982]: info: Received Get System Info May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:39:50 volumio volumio[982]: info: Discovery: Getting this device information May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:50 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:50 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 08 21:39:50 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:39:52 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 21:39:52 volumio volumio[982]: info: Received Get System Info May 08 21:39:52 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:39:52 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:39:52 volumio volumio[982]: info: Discovery: Getting this device information May 08 21:39:52 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:52 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:52 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:39:52 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 08 21:39:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 21:39:53 volumio volumio[982]: info: Received Get System Info May 08 21:39:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:39:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:39:53 volumio volumio[982]: info: Discovery: Getting this device information May 08 21:39:53 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:39:53 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:53 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPlay May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::play index 559 May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::stop May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::stop May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand stop took 11 milliseconds May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/RASPBERRY/Piosenki/Polskie/Ryszard Rynkowski/04 Nie budźcie marzeń ze snów.flac May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand stop took 2 milliseconds May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:58 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand clear took 4 milliseconds May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/RASPBERRY/Piosenki/Polskie/Ryszard Rynkowski/04 Nie budźcie marzeń ze snów.flac" May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: 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":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:39:58 volumio volumio[982]: verbose: CURRENT POSITION 559 May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: No code May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 61ms May 08 21:39:58 volumio volumio[982]: error: updateQueue error: null May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:58 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand status took 58 milliseconds May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 58ms May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand add "USB/RASPBERRY/Piosenki/Polskie/Ryszard Rynkowski/04 Nie budźcie marzeń ze snów.flac" took 55 milliseconds May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:39:58 volumio volumio[982]: verbose: CURRENT POSITION 559 May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: No code May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 143ms May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:58 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 109ms May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand play took 106 milliseconds May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 23ms May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:39:58 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:39:58 volumio volumio[982]: info: May 08 21:39:58 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::getState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:39:58 volumio volumio[982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 08 21:39:58 volumio volumio[982]: info: FusionDsp - ---- read samplerate from file: 44100 May 08 21:39:58 volumio volumio[982]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 08 21:39:58 volumio volumio[982]: info: FusionDsp - else 1 -2.5 May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand status took 18 milliseconds May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand status took 18 milliseconds May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 18ms May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand status took 16 milliseconds May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:39:58 volumio volumio[982]: error: FusionDsp - WebSocket error: [object Object] May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand playlistinfo took 10 milliseconds May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand playlistinfo took 10 milliseconds May 08 21:39:58 volumio volumio[982]: info: sendMpdCommand playlistinfo took 11 milliseconds May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:58 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"169 Kbps","isStreaming":false,"title":"Nie budźcie marzeń ze snów","artist":"Ryszard Rynkowski","album":"Złote przeboje","uri":"USB/RASPBERRY/Piosenki/Polskie/Ryszard Rynkowski/04 Nie budźcie marzeń ze snów.flac","trackType":"flac"} May 08 21:39:58 volumio volumio[982]: verbose: CURRENT POSITION 559 May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"169 Kbps","isStreaming":false,"title":"Nie budźcie marzeń ze snów","artist":"Ryszard Rynkowski","album":"Złote przeboje","uri":"USB/RASPBERRY/Piosenki/Polskie/Ryszard Rynkowski/04 Nie budźcie marzeń ze snów.flac","trackType":"flac"} May 08 21:39:58 volumio volumio[982]: verbose: CURRENT POSITION 559 May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:58 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"169 Kbps","isStreaming":false,"title":"Nie budźcie marzeń ze snów","artist":"Ryszard Rynkowski","album":"Złote przeboje","uri":"USB/RASPBERRY/Piosenki/Polskie/Ryszard Rynkowski/04 Nie budźcie marzeń ze snów.flac","trackType":"flac"} May 08 21:39:58 volumio volumio[982]: verbose: CURRENT POSITION 559 May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:39:58 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:39:58 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:39:58 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 56ms May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 97ms May 08 21:39:58 volumio volumio[982]: info: ------------------------------ 94ms May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPlay May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::play index 556 May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::stop May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::stPlaybackTimer May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::updateTrackBlock May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrackBlock May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::serviceStop May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 559 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::serviceStop May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::stop May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand stop took 11 milliseconds May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::getState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::play index undefined May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::startPlaybackTimer May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::clearAddPlayTracks USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand status took 4 milliseconds May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand stop took 1 milliseconds May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand clear May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::getState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:40:00 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand clear took 3 milliseconds May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand status took 3 milliseconds May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand add "USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac" May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: 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":"Nie budźcie marzeń ze snów","artist":"Ryszard Rynkowski","album":"Złote przeboje","uri":"USB/RASPBERRY/Piosenki/Polskie/Ryszard Rynkowski/04 Nie budźcie marzeń ze snów.flac","trackType":"flac"} May 08 21:40:00 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: No code May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:40:00 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState stateService stop May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: No code May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 89ms May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 83ms May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:40:00 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:40:00 volumio volumio[982]: error: updateQueue error: null May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:40:00 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 86ms May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand add "USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac" took 84 milliseconds May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 4ms May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand play May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces system playlist update May 08 21:40:00 volumio volumio[982]: info: Ignoring MPD Status Update May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 32ms May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand play took 30 milliseconds May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::getState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 30ms May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::getState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::getState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:40:00 volumio volumio[982]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 08 21:40:00 volumio volumio[982]: info: FusionDsp - ---- read samplerate from file: 44100 May 08 21:40:00 volumio volumio[982]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 08 21:40:00 volumio volumio[982]: info: FusionDsp - else 1 -2.5 May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand status took 21 milliseconds May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand status took 20 milliseconds May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand status took 19 milliseconds May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:40:00 volumio volumio[982]: info: May 08 21:40:00 volumio volumio[982]: ---------------------------- MPD announces state update: player May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::getState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand status May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand playlistinfo took 9 milliseconds May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand playlistinfo took 9 milliseconds May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand playlistinfo took 9 milliseconds May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand status took 7 milliseconds May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseState May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:40:00 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus stop May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:40:00 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:40:00 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:40:00 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:40:00 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 58ms May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 95ms May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 92ms May 08 21:40:00 volumio volumio[982]: error: FusionDsp - WebSocket error: [object Object] May 08 21:40:00 volumio volumio[982]: info: sendMpdCommand playlistinfo took 64 milliseconds May 08 21:40:00 volumio volumio[982]: verbose: ControllerMpd::parseTrackInfo May 08 21:40:00 volumio volumio[982]: info: ControllerMpd::pushState May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::servicePushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"Zgodnie Z Planem","artist":"Raz Dwa Trzy","album":"Raz Dwa Trzy 25 Lat","uri":"USB/RASPBERRY/Piosenki/Polskie/Raz Dwa Trzy 25 Lat/Raz Dwa Trzy - Zgodnie Z Planem.flac","trackType":"flac"} May 08 21:40:00 volumio volumio[982]: verbose: CURRENT POSITION 556 May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState stateService play May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::syncState currentStatus play May 08 21:40:00 volumio volumio[982]: info: Received an update from plugin. extracting info from payload May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: CoreStateMachine::pushState May 08 21:40:00 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::volumioPushState May 08 21:40:00 volumio volumio[982]: info: ------------------------------ 105ms May 08 21:40:00 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 08 21:40:07 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 21:40:07 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 08 21:40:07 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:40:08 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 21:40:08 volumio volumio[982]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 08 21:40:08 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 08 21:40:08 volumio volumio[982]: info: Received Get System Version May 08 21:40:08 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 21:40:08 volumio volumio[982]: info: Received Get System Info May 08 21:40:08 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:40:08 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:40:08 volumio volumio[982]: info: Discovery: Getting this device information May 08 21:40:08 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:40:08 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:08 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:40:12 volumio volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:40:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:40:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:40:17 volumio volumio[982]: info: Discovery: Getting this device information May 08 21:40:17 volumio volumio[982]: info: CoreCommandRouter::volumioGetState May 08 21:40:17 volumio volumio[982]: info: CorePlayQueue::getTrack 556 May 08 21:40:17 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:40:20 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 21:40:20 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 08 21:40:22 volumio volumio[982]: info: Enabling plugin fusiondsp May 08 21:40:22 volumio volumio[982]: info: Loading plugin "fusiondsp"... May 08 21:40:22 volumio volumio[982]: info: Applying required configuration parameters for plugin fusiondsp May 08 21:40:22 volumio volumio[982]: info: Preparing to generate the ALSA configuration file May 08 21:40:22 volumio volumio[982]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 08 21:40:22 volumio volumio[982]: info: Reading ALSA contributions from plugins. May 08 21:40:22 volumio volumio[982]: info: Asound.conf file unchanged, so no further update is needed May 08 21:40:22 volumio volumio[982]: info: Output device has changed, restarting MPD May 08 21:40:22 volumio sudo[13403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 21:40:22 volumio volumio[982]: info: Output device has changed, restarting Shairport Sync May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:40:22 volumio sudo[13403]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:40:22 volumio sudo[13403]: pam_unix(sudo:session): session closed for user root May 08 21:40:22 volumio sudo[13405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 21:40:22 volumio sudo[13405]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:40:22 volumio systemd[1]: Stopping Music Player Daemon... May 08 21:40:22 volumio volumio[982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 21:40:22 volumio volumio[982]: info: PLUGIN START: fusiondsp May 08 21:40:22 volumio volumio[982]: info: Loading i18n strings for locale pl May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:22 volumio volumio[982]: info: FusionDsp - mixtype--------------------- None May 08 21:40:22 volumio volumio[982]: info: Preparing to generate the ALSA configuration file May 08 21:40:22 volumio volumio[982]: info: Done. May 08 21:40:22 volumio volumio[982]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 08 21:40:22 volumio volumio[982]: info: Reading ALSA contributions from plugins. May 08 21:40:22 volumio volumio[982]: info: MPD Permissions set May 08 21:40:22 volumio volumio[982]: info: Starting Shairport Sync May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:22 volumio sudo[13413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 21:40:22 volumio sudo[13413]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:40:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 21:40:22 volumio systemd[1]: shairport-sync.service: Succeeded. May 08 21:40:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:22 volumio volumio[982]: info: Asound.conf file unchanged, so no further update is needed May 08 21:40:22 volumio volumio[982]: info: Output device has changed, restarting MPD May 08 21:40:22 volumio sudo[13421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 21:40:22 volumio volumio[982]: info: Output device has changed, restarting Shairport Sync May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:22 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:40:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 21:40:22 volumio sudo[13421]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:40:22 volumio systemd[1]: mpd.service: Succeeded. May 08 21:40:22 volumio systemd[1]: Stopped Music Player Daemon. May 08 21:40:22 volumio sudo[13421]: pam_unix(sudo:session): session closed for user root May 08 21:40:22 volumio systemd[1]: Starting Music Player Daemon... May 08 21:40:22 volumio sudo[13413]: pam_unix(sudo:session): session closed for user root May 08 21:40:22 volumio sudo[13423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 21:40:22 volumio sudo[13423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:40:23 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 08 21:40:23 volumio systemd[1]: mpd.service: Succeeded. May 08 21:40:23 volumio systemd[1]: Stopped Music Player Daemon. May 08 21:40:23 volumio volumio[982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 21:40:23 volumio systemd[1]: Starting Music Player Daemon... May 08 21:40:23 volumio volumio[982]: info: MPD Permissions set May 08 21:40:23 volumio volumio[982]: info: Shairport-Sync Started May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:40:23 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:23 volumio volumio[982]: info: Starting Shairport Sync May 08 21:40:23 volumio volumio[982]: error: Upnp client error: Error: This socket has been ended by the other party May 08 21:40:23 volumio sudo[13440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 21:40:23 volumio sudo[13440]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:40:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 21:40:23 volumio systemd[1]: shairport-sync.service: Succeeded. May 08 21:40:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 21:40:23 volumio sudo[13433]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 08 21:40:23 volumio sudo[13433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:40:23 volumio sudo[13433]: pam_unix(sudo:session): session closed for user root May 08 21:40:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 21:40:23 volumio sudo[13440]: pam_unix(sudo:session): session closed for user root May 08 21:40:23 volumio volumio[982]: info: Shairport-Sync Started May 08 21:40:24 volumio volumio[982]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 08 21:40:24 volumio volumio[982]: 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 May 08 21:40:24 volumio volumio[982]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 08 21:40:24 volumio volumio[982]: info: camilladsp spawned new process with pid undefined, instance 2, run: true May 08 21:40:24 volumio volumio[982]: info: camilladsp service started and running in background, instance 2 May 08 21:40:24 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:40:24 volumio volumio[982]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 08 21:40:24 volumio volumio[982]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 08 21:40:24 volumio volumio[982]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 08 21:40:24 volumio volumio[982]: info: FusionDsp loaded May 08 21:40:24 volumio volumio[982]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:40:24 volumio volumio[982]: info: FusionDsp - Reporting Fusion DSP Enabled May 08 21:40:24 volumio volumio[982]: info: Adding Signal Path Element [object Object] May 08 21:40:24 volumio volumio[982]: info: Adding fusiondspeq DSP Signal Path Element May 08 21:40:24 volumio volumio[982]: info: FusionDsp - ---- installed callbackRead May 08 21:40:24 volumio sudo[13458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 08 21:40:24 volumio sudo[13458]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:40:24 volumio volumio[982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 21:40:24 volumio volumio[982]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 08 21:40:24 volumio volumio[982]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 08 21:40:24 volumio volumio[982]: at onErrorNT (internal/child_process.js:465:16) May 08 21:40:24 volumio volumio[982]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 08 21:40:24 volumio volumio[982]: at runNextTicks (internal/process/task_queues.js:62:3) May 08 21:40:24 volumio volumio[982]: at listOnTimeout (internal/timers.js:523:9) May 08 21:40:24 volumio volumio[982]: at processTimers (internal/timers.js:497:7) { May 08 21:40:24 volumio volumio[982]: errno: -2, May 08 21:40:24 volumio volumio[982]: code: 'ENOENT', May 08 21:40:24 volumio volumio[982]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 08 21:40:24 volumio volumio[982]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 08 21:40:24 volumio volumio[982]: spawnargs: [ May 08 21:40:24 volumio volumio[982]: '-p', May 08 21:40:24 volumio volumio[982]: 9876, May 08 21:40:24 volumio volumio[982]: '-o', May 08 21:40:24 volumio volumio[982]: '/tmp/camilladsp.log', May 08 21:40:24 volumio volumio[982]: '-l', May 08 21:40:24 volumio volumio[982]: 'warn', May 08 21:40:24 volumio volumio[982]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 08 21:40:24 volumio volumio[982]: ] May 08 21:40:24 volumio volumio[982]: } May 08 21:40:24 volumio volumio[982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 21:40:24 volumio systemd[1]: Started FusionDsp Daemon. May 08 21:40:24 volumio sudo[13458]: pam_unix(sudo:session): session closed for user root May 08 21:40:25 volumio volumio[13460]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory May 08 21:40:25 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT May 08 21:40:25 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 08 21:40:25 volumio sudo[13470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-08 21:39 May 08 21:40:25 volumio sudo[13470]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"