-- Logs begin at Wed 2025-07-09 11:34:04 UTC, end at Wed 2025-07-09 11:40:20 UTC. -- Jul 09 11:39:01 volumio volumio[2944]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 09 11:39:01 volumio volumio[2944]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 09 11:39:01 volumio volumio[2944]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 09 11:39:01 volumio volumio[2944]: info: camilladsp service started and running in background, instance 1 Jul 09 11:39:01 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 11:39:01 volumio volumio[2944]: cannot open device 'volumioHw': Device or resource busy Jul 09 11:39:01 volumio volumio[2944]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 09 11:39:01 volumio volumio[2944]: cannot open device 'volumioHw': Device or resource busy Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp loaded Jul 09 11:39:01 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 11:39:01 volumio sudo[3196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 09 11:39:01 volumio sudo[3196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 09 11:39:01 volumio volumio[2944]: info: Adding Signal Path Element [object Object] Jul 09 11:39:01 volumio volumio[2944]: info: Adding fusiondspeq DSP Signal Path Element Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - ---- installed callbackRead Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 11:39:01 volumio sudo[3196]: pam_unix(sudo:session): session closed for user root Jul 09 11:39:01 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:39:01 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:39:01 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:39:01 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:39:01 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:39:01 volumio volumio[2944]: info: CoreCommandRouter::volumioPlay Jul 09 11:39:01 volumio volumio[2944]: info: CoreStateMachine::play index 16 Jul 09 11:39:01 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:01 volumio volumio[2944]: info: CoreStateMachine::stop Jul 09 11:39:01 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:01 volumio volumio[2944]: info: CoreStateMachine::play index undefined Jul 09 11:39:01 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:01 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:01 volumio volumio[2944]: info: CoreStateMachine::startPlaybackTimer Jul 09 11:39:01 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:01 volumio volumio[2944]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/Beyond - 真的爱你.flac Jul 09 11:39:01 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:01 volumio volumio[2944]: error: MPD error: The expression evaluated to a falsy value: Jul 09 11:39:01 volumio volumio[2944]: assert.ok(self.idling) Jul 09 11:39:01 volumio volumio[2944]: error: The expression evaluated to a falsy value: Jul 09 11:39:01 volumio volumio[2944]: assert.ok(self.idling) Jul 09 11:39:01 volumio volumio[2944]: error: MPD error: The expression evaluated to a falsy value: Jul 09 11:39:01 volumio volumio[2944]: assert.ok(self.idling) Jul 09 11:39:01 volumio volumio[2944]: error: The expression evaluated to a falsy value: Jul 09 11:39:01 volumio volumio[2944]: assert.ok(self.idling) Jul 09 11:39:01 volumio volumio[2944]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 09 11:39:01 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:01 volumio volumio[2944]: info: sendMpdCommand stop took 20 milliseconds Jul 09 11:39:01 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 11:39:01 volumio volumio[2944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 09 11:39:01 volumio volumio[2944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:39:01 volumio volumio[2944]: info: Jul 09 11:39:01 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:01 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:01 volumio volumio[2944]: info: sendMpdCommand clear took 368 milliseconds Jul 09 11:39:01 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/Beyond - 真的爱你.flac" Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 09 11:39:01 volumio volumio[2944]: info: Jul 09 11:39:01 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:01 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:01 volumio volumio[2944]: info: Jul 09 11:39:01 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:01 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:01 volumio volumio[2944]: info: Jul 09 11:39:01 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:01 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:01 volumio sudo[3217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 11:39:01 volumio sudo[3217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 11:39:01 volumio sudo[3217]: pam_unix(sudo:session): session closed for user root Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:39:01 volumio sudo[3220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 11:39:01 volumio sudo[3220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 11:39:01 volumio sudo[3220]: pam_unix(sudo:session): session closed for user root Jul 09 11:39:01 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:39:02 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:39:02 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:39:02 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:39:02 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:02 volumio volumio[2944]: info: Jul 09 11:39:02 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:02 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:02 volumio volumio[2944]: info: ------------------------------ 173ms Jul 09 11:39:02 volumio volumio[2944]: info: sendMpdCommand add "USB/500g/mp3/处理完成/Beyond - 真的爱你.flac" took 171 milliseconds Jul 09 11:39:02 volumio volumio[2944]: info: ------------------------------ 168ms Jul 09 11:39:02 volumio volumio[2944]: info: ------------------------------ 168ms Jul 09 11:39:02 volumio volumio[2944]: info: ------------------------------ 168ms Jul 09 11:39:02 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand play Jul 09 11:39:02 volumio volumio[2944]: info: Jul 09 11:39:02 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:02 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:02 volumio volumio[2944]: info: Jul 09 11:39:02 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:02 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:02 volumio volumio[2944]: info: Jul 09 11:39:02 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:02 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:02 volumio volumio[2944]: info: ------------------------------ 9ms Jul 09 11:39:02 volumio volumio[2944]: info: sendMpdCommand play took 7 milliseconds Jul 09 11:39:02 volumio volumio[2944]: info: ------------------------------ 7ms Jul 09 11:39:02 volumio volumio[2944]: info: ------------------------------ 6ms Jul 09 11:39:02 volumio volumio[2944]: info: ------------------------------ 5ms Jul 09 11:39:02 volumio volumio[2944]: info: Jul 09 11:39:02 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:02 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:02 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:02 volumio volumio[2944]: info: Jul 09 11:39:02 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:02 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:02 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:02 volumio volumio[2944]: info: Jul 09 11:39:02 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:02 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:02 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:02 volumio volumio[2944]: info: Jul 09 11:39:02 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:02 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:02 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:02 volumio volumio[2944]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jul 09 11:39:02 volumio volumio[2944]: info: FusionDsp - ---- read samplerate from file: 96000 Jul 09 11:39:02 volumio volumio[2944]: info: camilladsp stopping service pid 3186... Jul 09 11:39:02 volumio volumio[2944]: info: camilladsp service terminated, instance 1 Jul 09 11:39:02 volumio volumio[2944]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 11:39:03 volumio volumio[2944]: info: camilladsp service started and running in background, instance 1 Jul 09 11:39:05 volumio volumio[2944]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E6%B5%B7%E5%B3%B0/32d5d43f-d675-40d9-bcbe-eaa3b167ad03.jpg' Jul 09 11:39:05 volumio volumio[2944]: info: Jul 09 11:39:05 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand status took 3719 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand status took 3718 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand status took 3717 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand status took 3710 milliseconds Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:05 volumio volumio[2944]: info: Jul 09 11:39:05 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:05 volumio volumio[2944]: info: Jul 09 11:39:05 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:05 volumio volumio[2944]: info: Jul 09 11:39:05 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::volumioPlay Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::play index 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::stop Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::play index undefined Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::startPlaybackTimer Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/Beyond - 真的爱你.flac Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:05 volumio volumio[2944]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 09 11:39:05 volumio volumio[2944]: info: Jul 09 11:39:05 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand status took 48 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand status took 43 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand status took 42 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand status took 42 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: sendMpdCommand stop took 37 milliseconds Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1523 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:05 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1523 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:05 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:05 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1523 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:05 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:05 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1523 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:05 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:05 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:05 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:05 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:05 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:05 volumio volumio[2944]: info: ------------------------------ 3782ms Jul 09 11:39:05 volumio volumio[2944]: info: ------------------------------ 3782ms Jul 09 11:39:05 volumio volumio[2944]: info: ------------------------------ 3782ms Jul 09 11:39:05 volumio volumio[2944]: info: ------------------------------ 3782ms Jul 09 11:39:05 volumio volumio[2944]: info: Jul 09 11:39:05 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:05 volumio volumio[2944]: info: Jul 09 11:39:05 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:05 volumio volumio[2944]: info: Jul 09 11:39:05 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:05 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:05 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPlay Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::play index 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::stPlaybackTimer Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::updateTrackBlock Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrackBlock Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::serviceStop Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::serviceStop Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::stop Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:06 volumio sudo[3244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 09 11:39:06 volumio sudo[3244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 11:39:06 volumio sudo[3244]: pam_unix(sudo:session): session closed for user root Jul 09 11:39:06 volumio volumio[2944]: info: Upmpdcli Daemon Started Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: Cannot compose Albumart path Jul 09 11:39:06 volumio volumio[2944]: Cannot compose Albumart path Jul 09 11:39:06 volumio volumio[2944]: Cannot compose Albumart path Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 895 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 894 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 893 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 893 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 894 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand clear took 894 milliseconds Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/Beyond - 真的爱你.flac" Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":876,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1506 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":876,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1506 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:06 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":876,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1506 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:06 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":876,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1506 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:06 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 955ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 955ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 955ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 954ms Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:06 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:06 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 897 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 896 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 896 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand stop took 872 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 811ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 811ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 811ms Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand add "USB/500g/mp3/处理完成/Beyond - 真的爱你.flac" took 21 milliseconds Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::play index undefined Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand play Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:06 volumio volumio[2944]: 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} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::play index undefined Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: 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} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: No code Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: 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} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: No code Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::startPlaybackTimer Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/Brian Crain - canon in d.flac Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:06 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::startPlaybackTimer Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/Brian Crain - canon in d.flac Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 921ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 920ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 920ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 944ms Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 38ms Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand play took 36 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand stop took 25 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand stop took 14 milliseconds Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jul 09 11:39:06 volumio volumio[2944]: info: FusionDsp - ---- read samplerate from file: 96000 Jul 09 11:39:06 volumio volumio[2944]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:06 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:06 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 17 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 17 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand clear took 17 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand clear took 16 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 5ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 4ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 4ms Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/Brian Crain - canon in d.flac" Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/Brian Crain - canon in d.flac" Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:06 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:06 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 6ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 6ms Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand add "USB/500g/mp3/处理完成/Brian Crain - canon in d.flac" took 5 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand add "USB/500g/mp3/处理完成/Brian Crain - canon in d.flac" took 5 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 4ms Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 4 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 4ms Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 3 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 3ms Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand status took 3 milliseconds Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand play Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand play Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:06 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:06 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: 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} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: No code Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: 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} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: No code Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: 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} Jul 09 11:39:06 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState stateService stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: No code Jul 09 11:39:06 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:06 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:06 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 36ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 36ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 27ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 27ms Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:06 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:06 volumio volumio[2944]: error: FusionDsp - WebSocket error: [object Object] Jul 09 11:39:06 volumio volumio[2944]: info: Jul 09 11:39:06 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 29ms Jul 09 11:39:06 volumio volumio[2944]: info: ------------------------------ 28ms Jul 09 11:39:06 volumio volumio[2944]: info: sendMpdCommand play took 26 milliseconds Jul 09 11:39:06 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:06 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:07 volumio volumio[2944]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jul 09 11:39:07 volumio volumio[2944]: info: FusionDsp - ---- read samplerate from file: 96000 Jul 09 11:39:07 volumio volumio[2944]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 11:39:07 volumio volumio[2944]: info: Jul 09 11:39:07 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:07 volumio volumio[2944]: info: ------------------------------ 29ms Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:07 volumio volumio[2944]: info: ------------------------------ 33ms Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand status took 30 milliseconds Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:07 volumio volumio[2944]: info: Jul 09 11:39:07 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:07 volumio volumio[2944]: info: Jul 09 11:39:07 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:07 volumio volumio[2944]: info: Jul 09 11:39:07 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand status took 16 milliseconds Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand status took 16 milliseconds Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand status took 16 milliseconds Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand status took 15 milliseconds Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"427 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:07 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:07 volumio volumio[2944]: info: ------------------------------ 50ms Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 09 11:39:07 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:07 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"430 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:07 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:07 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"393 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:07 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:07 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"393 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:07 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:07 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:07 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"393 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:07 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:07 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:07 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:07 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:07 volumio volumio[2944]: info: ------------------------------ 45ms Jul 09 11:39:07 volumio volumio[2944]: info: ------------------------------ 40ms Jul 09 11:39:07 volumio volumio[2944]: info: ------------------------------ 40ms Jul 09 11:39:07 volumio volumio[2944]: info: ------------------------------ 39ms Jul 09 11:39:07 volumio volumio[2944]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 09 11:39:07 volumio volumio[2944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 09 11:39:07 volumio volumio[2944]: info: Adding plugin bluetooth to MyMusic Plugins Jul 09 11:39:07 volumio volumio[2944]: info: Adding plugin multiroom to MyMusic Plugins Jul 09 11:39:07 volumio volumio[2944]: info: Adding plugin metavolumio to MyMusic Plugins Jul 09 11:39:07 volumio volumio[2944]: info: Adding plugin cd_controller to MyMusic Plugins Jul 09 11:39:07 volumio volumio[2944]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 09 11:39:07 volumio volumio[2944]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 09 11:39:07 volumio volumio[2944]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:39:07 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:39:07 volumio volumio[2944]: info: Starting MyVolumio Remote Streaming Endpoints Jul 09 11:39:07 volumio volumio[2944]: info: MyVolumio login type: Token Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 09 11:39:07 volumio volumio[2944]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 09 11:39:07 volumio volumio-remote-updater[727]: [2025-07-09 11:39:07] [connect] Successful connection Jul 09 11:39:08 volumio volumio[2944]: info: Starting Streaming Service Transparent Proxy Jul 09 11:39:08 volumio volumio[2944]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 09 11:39:08 volumio volumio[2944]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 09 11:39:08 volumio volumio[2944]: info: Streaming services startup Jul 09 11:39:08 volumio volumio[2944]: info: Starting Streaming Daemon Jul 09 11:39:08 volumio sudo[3308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 09 11:39:08 volumio sudo[3308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 11:39:08 volumio sudo[3308]: pam_unix(sudo:session): session closed for user root Jul 09 11:39:08 volumio volumio[2944]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 09 11:39:08 volumio volumio[2944]: error: Cannot start Volumio Streaming Daemon Jul 09 11:39:08 volumio volumio[2944]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 09 11:39:08 volumio volumio[2944]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 09 11:39:08 volumio volumio-remote-updater[727]: [2025-07-09 11:39:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752061147 101 Jul 09 11:39:08 volumio volumio[2944]: 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: 11 Jul 09 11:39:08 volumio volumio[2944]: STREAMING PROXY: Starting server on port 3245 Jul 09 11:39:08 volumio volumio[2944]: Node JS runtime: 14 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPlay Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::play index 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::stop Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::stPlaybackTimer Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::updateTrackBlock Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrackBlock Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::serviceStop Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::serviceStop Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::stop Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand stop took 4 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::play index undefined Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::startPlaybackTimer Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/Beyond - 真的爱你.flac Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 1 milliseconds Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand stop took 2 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 2 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 1 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 1 milliseconds Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: 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":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService stop Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: No code Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 15ms Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:08 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand clear took 11 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/Beyond - 真的爱你.flac" Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:08 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:08 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 15ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 15ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 14ms Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:08 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:08 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:08 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:08 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:08 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 8ms Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand add "USB/500g/mp3/处理完成/Beyond - 真的爱你.flac" took 7 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 6ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 5ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 5ms Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand play Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:08 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:08 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:08 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 3ms Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand play took 2 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 3ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 2ms Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 4ms Jul 09 11:39:08 volumio volumio[2944]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jul 09 11:39:08 volumio volumio[2944]: info: FusionDsp - ---- read samplerate from file: 96000 Jul 09 11:39:08 volumio volumio[2944]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 11:39:08 volumio volumio[2944]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jul 09 11:39:08 volumio volumio[2944]: info: FusionDsp - ---- read samplerate from file: 96000 Jul 09 11:39:08 volumio volumio[2944]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 42 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 42 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 41 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 41 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: Jul 09 11:39:08 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 4 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 3 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand status took 2 milliseconds Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1538 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:08 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1538 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:08 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1538 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:08 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 58ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 68ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 67ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 68ms Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 09 11:39:08 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1968 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:08 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1968 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:08 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1968 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:08 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1968 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:08 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:08 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:08 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:08 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 65ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 62ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 62ms Jul 09 11:39:08 volumio volumio[2944]: info: ------------------------------ 61ms Jul 09 11:39:08 volumio volumio[2944]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 09 11:39:08 volumio volumio[2944]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 09 11:39:09 volumio volumio[2944]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jul 09 11:39:09 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:39:14 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:39:14 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 11:39:14 volumio volumio[2944]: info: Received Get System Info Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 11:39:14 volumio volumio[2944]: info: Discovery: Getting this device information Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:39:14 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:39:14 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:14 volumio volumio[2944]: info: Listing playlists Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::volumioGetQueue Jul 09 11:39:14 volumio volumio[2944]: info: CoreStateMachine::getQueue Jul 09 11:39:14 volumio volumio[2944]: info: CorePlayQueue::getQueue Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:39:14 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:39:14 volumio volumio[2944]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%87%91%E8%8E%8E/5da0a24e-e627-4e2a-a872-73db96a26261.jpg' Jul 09 11:39:14 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:39:14 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:39:14 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:39:14 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:39:14 volumio volumio[2944]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%9B%A7%E5%B7%A7%E9%9F%B3/4%20Seasons%20In%20One%20Day/a3c71e7f-59a1-46e4-af73-fcf6f5361482.png' Jul 09 11:39:15 volumio volumio[2944]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E5%BF%83%E5%87%8C/Cyndi%20Loves%20You/6a30f5ce-a68a-43f1-a76f-7f41e97a85ee.jpg' Jul 09 11:39:15 volumio volumio[2944]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%BB%8E%E6%98%8E/Dawn/3d1ee9b7-75cb-4680-8038-747c49c0a267.jpg' Jul 09 11:39:16 volumio thd[706]: Executing trigger action: /usr/local/bin/volumio volume minus Jul 09 11:39:16 volumio thd[706]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 11:39:16 volumio thd[706]: Dload Upload Total Spent Left Speed Jul 09 11:39:16 volumio volumio[2944]: info: Jul 09 11:39:16 volumio volumio[2944]: ---------------------------- Client requests Volume - Jul 09 11:39:16 volumio volumio[2944]: info: VolumeController::SetAlsaVolume- Jul 09 11:39:16 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:16 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:16 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:16 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:16 volumio thd[706]: [237B blob data] Jul 09 11:39:17 volumio thd[706]: {"time":1752061156333,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Jul 09 11:39:17 volumio thd[706]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 11:39:17 volumio thd[706]: Dload Upload Total Spent Left Speed Jul 09 11:39:17 volumio volumio[2944]: info: Jul 09 11:39:17 volumio volumio[2944]: ---------------------------- Client requests Volume - Jul 09 11:39:17 volumio volumio[2944]: info: VolumeController::SetAlsaVolume- Jul 09 11:39:17 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:17 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:17 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:17 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:17 volumio thd[706]: [158B blob data] Jul 09 11:39:17 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 09 11:39:18 volumio thd[706]: {"time":1752061157290,"response":"volume Success"}Executing trigger action: /usr/local/bin/volumio volume minus Jul 09 11:39:18 volumio thd[706]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 11:39:18 volumio thd[706]: Dload Upload Total Spent Left Speed Jul 09 11:39:18 volumio volumio[2944]: info: Jul 09 11:39:18 volumio volumio[2944]: ---------------------------- Client requests Volume - Jul 09 11:39:18 volumio volumio[2944]: info: VolumeController::SetAlsaVolume- Jul 09 11:39:18 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:18 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:18 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:18 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:18 volumio thd[706]: [158B blob data] Jul 09 11:39:20 volumio volumio[2944]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 09 11:39:23 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 09 11:39:23 volumio volumio[2944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jul 09 11:39:23 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:39:23 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:23 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 11:39:23 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:39:23 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 11:39:24 volumio volumio-remote-updater[727]: No test mode Jul 09 11:39:24 volumio volumio-remote-updater[727]: No alpha test mode Jul 09 11:39:24 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 09 11:39:28 volumio volumio[2944]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v3.819","updateavailable":true} Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::volumioGetBrowseSources Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 11:39:28 volumio volumio[2944]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 09 11:39:28 volumio volumio[2944]: info: Completed starting MyVolumio Plugin Jul 09 11:39:28 volumio volumio[2944]: [Metrics] CommandRouter: 37s 578.79ms Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::volumiosetStartupVolume Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::Close All Modals sent Jul 09 11:39:28 volumio volumio[2944]: info: CoreCommandRouter::Close All Modals sent Jul 09 11:39:28 volumio volumio[2944]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 09 11:39:28 volumio volumio[2944]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 09 11:39:28 volumio volumio[2944]: info: camilladsp stopping service pid 3248... Jul 09 11:39:28 volumio volumio[2944]: info: camilladsp service terminated, instance 1 Jul 09 11:39:28 volumio volumio[2944]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 11:39:28 volumio volumio[2944]: info: camilladsp service started and running in background, instance 1 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioSeek Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::seek Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: TRACKBLOCK {"uri":"mnt/USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","service":"mpd","name":"真的爱你","artist":"Beyond ","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=571&web=Beyond%20//extralarge&path=%2FUSB%2F500g%2Fmp3%2F%E5%A4%84%E7%90%86%E5%AE%8C%E6%88%90&metadata=false","duration":275,"trackType":"flac","samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2} Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::startPlaybackTimer Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::seek Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: BOOT COMPLETED Jul 09 11:39:35 volumio volumio[2944]: error: null Jul 09 11:39:35 volumio volumio[2944]: info: Jul 09 11:39:35 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:35 volumio volumio[2944]: info: Jul 09 11:39:35 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:35 volumio volumio[2944]: info: Jul 09 11:39:35 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:35 volumio volumio[2944]: info: Jul 09 11:39:35 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:35 volumio volumio[2944]: info: sendMpdCommand status took 12 milliseconds Jul 09 11:39:35 volumio volumio[2944]: info: sendMpdCommand status took 12 milliseconds Jul 09 11:39:35 volumio volumio[2944]: info: sendMpdCommand status took 11 milliseconds Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:35 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 09 11:39:35 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 09 11:39:35 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 09 11:39:35 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:35 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50000,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1711 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:35 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:35 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50000,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1685 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:35 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:35 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50000,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1685 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:35 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:35 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50000,"duration":275,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1685 Kbps","isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:35 volumio volumio[2944]: verbose: CURRENT POSITION 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:35 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:35 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:35 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:35 volumio volumio[2944]: info: ------------------------------ 60ms Jul 09 11:39:35 volumio volumio[2944]: info: ------------------------------ 59ms Jul 09 11:39:35 volumio volumio[2944]: info: ------------------------------ 56ms Jul 09 11:39:35 volumio volumio[2944]: info: ------------------------------ 57ms Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::volumioPlay Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::play index 17 Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::stop Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::stPlaybackTimer Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::updateTrackBlock Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrackBlock Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::serviceStop Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrack 16 Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::serviceStop Jul 09 11:39:42 volumio volumio[2944]: info: ControllerMpd::stop Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:42 volumio volumio[2944]: info: Jul 09 11:39:42 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand stop took 14 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::play index undefined Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::startPlaybackTimer Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::clearAddPlayTracks USB/500g/mp3/处理完成/Brian Crain - canon in d.flac Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 11:39:42 volumio volumio[2944]: info: Jul 09 11:39:42 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:42 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:42 volumio volumio[2944]: info: Jul 09 11:39:42 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:42 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:42 volumio volumio[2944]: info: Jul 09 11:39:42 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:42 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand status took 6 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand stop took 5 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand status took 4 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand status took 3 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand status took 3 milliseconds Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:42 volumio volumio[2944]: info: Jul 09 11:39:42 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:42 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand clear took 4 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 09 11:39:42 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand add "USB/500g/mp3/处理完成/Brian Crain - canon in d.flac" Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:42 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:42 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:42 volumio volumio[2944]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"真的爱你","artist":"Beyond ","album":null,"uri":"USB/500g/mp3/处理完成/Beyond - 真的爱你.flac","trackType":"flac"} Jul 09 11:39:42 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::syncState stateService stop Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:42 volumio volumio[2944]: info: No code Jul 09 11:39:42 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:42 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:42 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:42 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:42 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:42 volumio volumio[2944]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 33ms Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 30ms Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 30ms Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 29ms Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:43 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:43 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:43 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:43 volumio volumio[2944]: error: updateQueue error: null Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:43 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 26ms Jul 09 11:39:43 volumio volumio[2944]: info: sendMpdCommand add "USB/500g/mp3/处理完成/Brian Crain - canon in d.flac" took 25 milliseconds Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 3ms Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 4ms Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 3ms Jul 09 11:39:43 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand play Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:43 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:43 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces system playlist update Jul 09 11:39:43 volumio volumio[2944]: info: Ignoring MPD Status Update Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 12ms Jul 09 11:39:43 volumio volumio[2944]: info: sendMpdCommand play took 11 milliseconds Jul 09 11:39:43 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:43 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 12ms Jul 09 11:39:43 volumio volumio[2944]: info: ------------------------------ 11ms Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:43 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:43 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:43 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:43 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:43 volumio volumio[2944]: info: Jul 09 11:39:43 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:43 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:43 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:45 volumio volumio[2944]: info: Jul 09 11:39:45 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:45 volumio volumio[2944]: info: sendMpdCommand status took 2395 milliseconds Jul 09 11:39:45 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:45 volumio volumio[2944]: info: sendMpdCommand status took 2395 milliseconds Jul 09 11:39:45 volumio volumio[2944]: info: sendMpdCommand status took 2394 milliseconds Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:45 volumio volumio[2944]: info: Jul 09 11:39:45 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:45 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:45 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:45 volumio volumio[2944]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Jul 09 11:39:45 volumio volumio[2944]: info: FusionDsp - ---- read samplerate from file: 96000 Jul 09 11:39:45 volumio volumio[2944]: info: camilladsp stopping service pid 3377... Jul 09 11:39:45 volumio volumio[2944]: info: camilladsp service terminated, instance 1 Jul 09 11:39:45 volumio volumio[2944]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 09 11:39:47 volumio volumio[2944]: info: camilladsp service started and running in background, instance 1 Jul 09 11:39:50 volumio volumio[2944]: info: sendMpdCommand status took 4793 milliseconds Jul 09 11:39:50 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4793 milliseconds Jul 09 11:39:50 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 4792 milliseconds Jul 09 11:39:50 volumio volumio[2944]: info: sendMpdCommand status took 4792 milliseconds Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:50 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:50 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus stop Jul 09 11:39:50 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"454 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:50 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:50 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:50 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"428 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:50 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:50 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:50 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":523,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"402 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:50 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:50 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:50 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:50 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:50 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:50 volumio volumio[2944]: info: ------------------------------ 7205ms Jul 09 11:39:50 volumio volumio[2944]: info: ------------------------------ 7224ms Jul 09 11:39:50 volumio volumio[2944]: info: ------------------------------ 7223ms Jul 09 11:39:50 volumio volumio[2944]: info: ------------------------------ 7224ms Jul 09 11:39:50 volumio volumio[2944]: info: Jul 09 11:39:50 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:50 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:50 volumio volumio[2944]: info: Jul 09 11:39:50 volumio volumio[2944]: ---------------------------- MPD announces state update: player Jul 09 11:39:50 volumio volumio[2944]: info: ControllerMpd::getState Jul 09 11:39:50 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand status Jul 09 11:39:57 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 7310 milliseconds Jul 09 11:39:57 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 7309 milliseconds Jul 09 11:39:57 volumio volumio[2944]: info: sendMpdCommand status took 7274 milliseconds Jul 09 11:39:57 volumio volumio[2944]: info: sendMpdCommand status took 7273 milliseconds Jul 09 11:39:57 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:57 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:57 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:57 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:57 volumio volumio[2944]: verbose: ControllerMpd::parseState Jul 09 11:39:57 volumio volumio[2944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 11:39:57 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2117,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:57 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:57 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:57 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2117,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"438 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:57 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:57 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:57 volumio volumio[2944]: info: ------------------------------ 12136ms Jul 09 11:39:57 volumio volumio[2944]: info: ------------------------------ 12134ms Jul 09 11:39:57 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 76 milliseconds Jul 09 11:39:57 volumio volumio[2944]: info: sendMpdCommand playlistinfo took 77 milliseconds Jul 09 11:39:57 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:57 volumio volumio[2944]: verbose: ControllerMpd::parseTrackInfo Jul 09 11:39:57 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2117,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"438 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:57 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:57 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:57 volumio volumio[2944]: info: ControllerMpd::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::servicePushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2117,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"438 Kbps","isStreaming":false,"title":"canon in d","artist":"Brian Crain ","album":null,"uri":"USB/500g/mp3/处理完成/Brian Crain - canon in d.flac","trackType":"flac"} Jul 09 11:39:57 volumio volumio[2944]: verbose: CURRENT POSITION 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::syncState stateService play Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::syncState currentStatus play Jul 09 11:39:57 volumio volumio[2944]: info: Received an update from plugin. extracting info from payload Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:57 volumio volumio[2944]: info: CoreStateMachine::pushState Jul 09 11:39:57 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:39:57 volumio volumio[2944]: info: CoreCommandRouter::volumioPushState Jul 09 11:39:57 volumio volumio[2944]: info: ------------------------------ 7370ms Jul 09 11:39:57 volumio volumio[2944]: info: ------------------------------ 7370ms Jul 09 11:39:57 volumio volumio[2944]: info: MyVolumio token set successfully Jul 09 11:39:57 volumio volumio[2944]: info: MYVOLUMIO: Adding device Jul 09 11:39:57 volumio volumio[2944]: info: MYVOLUMIO: Evaluating Server Jul 09 11:39:57 volumio volumio[2944]: verbose: New Socket.io Connection to 192.168.5.243 from 192.168.5.163 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 09 11:39:57 volumio volumio[2944]: verbose: New Socket.io Connection to 192.168.5.243 from 192.168.5.163 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 09 11:39:57 volumio volumio[2944]: verbose: New Socket.io Connection to 192.168.5.243 from 192.168.5.163 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 09 11:39:58 volumio volumio[2944]: info: MyVolumio status changed Jul 09 11:39:58 volumio volumio[2944]: info: Streaming services startup Jul 09 11:39:58 volumio volumio[2944]: info: Starting Streaming Daemon Jul 09 11:39:58 volumio volumio[2944]: info: Removing browser output: myVolumio user plan is not superstar Jul 09 11:39:58 volumio volumio[2944]: info: Removing audio output: Jul 09 11:39:58 volumio volumio[2944]: info: Stoppping Tunnel 1 Jul 09 11:39:58 volumio sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 09 11:39:58 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 11:39:58 volumio sudo[3459]: pam_unix(sudo:session): session closed for user root Jul 09 11:39:58 volumio volumio[2944]: error: Cannot start Volumio Streaming Daemon Jul 09 11:39:58 volumio volumio[2944]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 09 11:39:58 volumio volumio[2944]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 09 11:39:58 volumio sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 09 11:39:58 volumio sudo[3462]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 11:39:58 volumio sudo[3462]: pam_unix(sudo:session): session closed for user root Jul 09 11:39:58 volumio volumio[2944]: info: Remote SSH Stopped Jul 09 11:39:58 volumio volumio[2944]: info: Setting Geolocation for MyVolumio to us2 Jul 09 11:39:58 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:39:58 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:39:58 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:40:00 volumio volumio[2944]: info: Updating MyVolumio device info Jul 09 11:40:00 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:40:01 volumio volumio[2944]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 09 11:40:01 volumio volumio[2944]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 09 11:40:02 volumio nmbd[914]: [2025/07/09 11:40:02.979606, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 09 11:40:02 volumio nmbd[914]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.243 for name WORKGROUP<1d>. Jul 09 11:40:02 volumio nmbd[914]: This response was from IP 192.168.5.1, reporting an IP address of 192.168.5.1. Jul 09 11:40:03 volumio volumio[2944]: verbose: New Socket.io Connection to 192.168.5.243 from 192.168.5.163 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:40:03 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:40:03 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 11:40:03 volumio volumio[2944]: info: Received Get System Info Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 11:40:03 volumio volumio[2944]: info: Discovery: Getting this device information Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:40:03 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::volumioGetState Jul 09 11:40:03 volumio volumio[2944]: info: CorePlayQueue::getTrack 17 Jul 09 11:40:03 volumio volumio[2944]: info: Listing playlists Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::volumioGetQueue Jul 09 11:40:03 volumio volumio[2944]: info: CoreStateMachine::getQueue Jul 09 11:40:03 volumio volumio[2944]: info: CorePlayQueue::getQueue Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - no preset used Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:40:03 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 09 11:40:03 volumio volumio[2944]: info: FusionDsp - undefined Jul 09 11:40:05 volumio volumio[2944]: info: MYVOLUMIO: Adding device Jul 09 11:40:05 volumio volumio[2944]: info: MYVOLUMIO: Evaluating Server Jul 09 11:40:08 volumio volumio[2944]: info: Setting Geolocation for MyVolumio to us2 Jul 09 11:40:08 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:40:10 volumio volumio[2944]: info: Updating MyVolumio device info Jul 09 11:40:10 volumio volumio[2944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 11:40:10 volumio volumio[2944]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 09 11:40:11 volumio volumio[2944]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 09 11:40:20 volumio volumio[2944]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 11:40:20 volumio volumio[2944]: Error: Client network socket disconnected before secure TLS connection was established Jul 09 11:40:20 volumio volumio[2944]: at connResetException (internal/errors.js:639:14) Jul 09 11:40:20 volumio volumio[2944]: at TLSSocket.onConnectEnd (_tls_wrap.js:1570:19) Jul 09 11:40:20 volumio volumio[2944]: at TLSSocket.emit (events.js:412:35) Jul 09 11:40:20 volumio volumio[2944]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 09 11:40:20 volumio volumio[2944]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Jul 09 11:40:20 volumio volumio[2944]: code: 'ECONNRESET', Jul 09 11:40:20 volumio volumio[2944]: path: null, Jul 09 11:40:20 volumio volumio[2944]: host: 'lastfm.freetls.fastly.net', Jul 09 11:40:20 volumio volumio[2944]: port: 443, Jul 09 11:40:20 volumio volumio[2944]: localAddress: undefined Jul 09 11:40:20 volumio volumio[2944]: } Jul 09 11:40:20 volumio volumio[2944]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 11:40:20 volumio sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-09 11:39 Jul 09 11:40:20 volumio sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"