-- Logs begin at Thu 2019-02-14 17:12:00 +07, end at Sun 2024-07-21 22:01:57 +07. -- Jul 21 22:00:01 volumio mpd[5148]: Jul 21 22:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 21 22:00:01 volumio systemd[1]: Started Music Player Daemon. Jul 21 22:00:01 volumio sudo[5110]: pam_unix(sudo:session): session closed for user root Jul 21 22:00:01 volumio sudo[5129]: pam_unix(sudo:session): session closed for user root Jul 21 22:00:01 volumio volumio[955]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 21 22:00:01 volumio volumio[955]: 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 21 22:00:01 volumio volumio[955]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 21 22:00:01 volumio volumio[955]: info: camilladsp spawned new process with pid 5160, instance 2, run: true Jul 21 22:00:01 volumio volumio[955]: info: camilladsp service started and running in background, instance 2 Jul 21 22:00:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:00:01 volumio systemd[1]: Started MPD OLED Plugin. Jul 21 22:00:01 volumio volumio[955]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jul 21 22:00:01 volumio volumio[955]: info: FusionDsp loaded Jul 21 22:00:01 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 22:00:01 volumio volumio[955]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 21 22:00:01 volumio volumio[955]: info: Adding Signal Path Element [object Object] Jul 21 22:00:01 volumio volumio[955]: info: Updating fusiondspeq DSP Signal Path Element Jul 21 22:00:01 volumio volumio[955]: info: FusionDsp - ---- installed callbackRead Jul 21 22:00:01 volumio volumio[955]: info: FusionDsp - ---- read samplerate, raw: Jul 21 22:00:01 volumio volumio[955]: error: FusionDsp - invalid sample rate Jul 21 22:00:01 volumio volumio[955]: info: FusionDsp - ---- read samplerate, raw: Jul 21 22:00:01 volumio volumio[955]: error: FusionDsp - invalid sample rate Jul 21 22:00:01 volumio sudo[5171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 21 22:00:01 volumio sudo[5171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:00:01 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:01 volumio sudo[5171]: pam_unix(sudo:session): session closed for user root Jul 21 22:00:01 volumio volumio[955]: error: MPD error: The expression evaluated to a falsy value: Jul 21 22:00:01 volumio volumio[955]: assert.ok(self.idling) Jul 21 22:00:01 volumio volumio[955]: error: The expression evaluated to a falsy value: Jul 21 22:00:01 volumio volumio[955]: assert.ok(self.idling) Jul 21 22:00:01 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 21 22:00:01 volumio volumio[955]: error: updateQueue error: null Jul 21 22:00:01 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:04 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:07 volumio ntpd[777]: Soliciting pool server 2406:da14:f91:a902:2ec6:60c6:276b:ddb Jul 21 22:00:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:10 volumio volumio[955]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Jul 21 22:00:10 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Jul 21 22:00:10 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:10 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:10 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jul 21 22:00:10 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:15 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Jul 21 22:00:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:15 volumio volumio[955]: info: CALLMETHOD: audio_interface fusiondsp reseteq Jul 21 22:00:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , reseteq Jul 21 22:00:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:15 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:15 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:16 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:16 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jul 21 22:00:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:17 volumio volumio[955]: info: CALLMETHOD: audio_interface fusiondsp moresettings Jul 21 22:00:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , moresettings Jul 21 22:00:17 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:17 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:17 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jul 21 22:00:18 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:19 volumio volumio[955]: info: CALLMETHOD: audio_interface fusiondsp lesssettings Jul 21 22:00:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , lesssettings Jul 21 22:00:19 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:19 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:19 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jul 21 22:00:20 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:20 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:20 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:23 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:23 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:23 volumio volumio[955]: info: CALLMETHOD: audio_interface fusiondsp disableeffect Jul 21 22:00:23 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Jul 21 22:00:23 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:23 volumio volumio[955]: info: FusionDsp - Effects disabled Jul 21 22:00:23 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:23 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jul 21 22:00:23 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:29 volumio volumio[955]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jul 21 22:00:29 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jul 21 22:00:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:30 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:30 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:30 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:30 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jul 21 22:00:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:34 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:34 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:34 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:34 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:35 volumio volumio[955]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] Jul 21 22:00:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset Jul 21 22:00:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:35 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:35 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:36 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:36 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jul 21 22:00:36 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:36 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:36 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:36 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:37 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:37 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:37 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:37 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:42 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:42 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:42 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:42 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:43 volumio volumio[955]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] Jul 21 22:00:43 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset Jul 21 22:00:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:44 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:00:44 volumio volumio[955]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 21 22:00:44 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 21 22:00:44 volumio volumio[955]: info: CoreCommandRouter::Close All Modals sent Jul 21 22:00:44 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:44 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:45 volumio volumio[955]: info: CoreCommandRouter::volumioPause Jul 21 22:00:45 volumio volumio[955]: info: CoreStateMachine::pause Jul 21 22:00:45 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Jul 21 22:00:45 volumio volumio[955]: info: CoreStateMachine::servicePause Jul 21 22:00:45 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:00:45 volumio volumio[955]: info: CoreCommandRouter::servicePause Jul 21 22:00:45 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:00:45 volumio volumio[955]: info: ControllerMpd::pause Jul 21 22:00:45 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand pause Jul 21 22:00:45 volumio volumio[955]: info: sendMpdCommand pause took 6 milliseconds Jul 21 22:00:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:47 volumio volumio[955]: info: CoreCommandRouter::volumioPause Jul 21 22:00:47 volumio volumio[955]: info: CoreStateMachine::pause Jul 21 22:00:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:49 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:49 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:49 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:49 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:52 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:52 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:52 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:52 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:56 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:56 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:56 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:56 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:56 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:56 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:57 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:57 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:57 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:57 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:58 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:58 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:58 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:58 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:58 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:58 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:59 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:59 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:59 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:59 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:59 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:00:59 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:00 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:00 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:00 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:00 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:01 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:02 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:03 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:04 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:06 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:07 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:07 volumio volumio[955]: info: CoreCommandRouter::volumioPause Jul 21 22:01:07 volumio volumio[955]: info: CoreStateMachine::pause Jul 21 22:01:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:08 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:09 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:09 volumio volumio[955]: info: CoreCommandRouter::volumioSeek Jul 21 22:01:09 volumio volumio[955]: info: CoreStateMachine::seek Jul 21 22:01:09 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:09 volumio volumio[955]: info: TRACKBLOCK {"service":"youtube2","uri":"youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22icsCs1sF58A%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%2233%20B%E1%BA%A2N%20NH%E1%BA%A0C%20T%C3%8CNH%20CA%20X%C6%AFA%20L%C3%83NG%20M%E1%BA%A0N%20KH%C3%94NG%20TH%E1%BB%82%20THI%E1%BA%BEU%20M%E1%BB%96I%20NG%C3%80Y%20-%20RONG%20R%C3%8AU%20%7C%20T%C3%8CNH%20KH%C3%9AC%20H%E1%BA%A2I%20NGO%E1%BA%A0I%20B%E1%BA%A4T%20H%E1%BB%A6%22%2C%22artist%22%3A%22Long%20Ho%C3%A0ng%20Audio%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FicsCs1sF58A%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLB3JCaVluCZHgeGQ7CYdSG49g8dRQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22icsCs1sF58A%22%7D%7D%7D","albumart":"https://i.ytimg.com/vi_webp/icsCs1sF58A/maxresdefault.webp","artist":"Long Hoàng Audio","album":"YouTube","name":"33 BẢN NHẠC TÌNH CA XƯA LÃNG MẠN KHÔNG THỂ THIẾU MỖI NGÀY - RONG RÊU | TÌNH KHÚC HẢI NGOẠI BẤT HỦ","title":"33 BẢN NHẠC TÌNH CA XƯA LÃNG MẠN KHÔNG THỂ THIẾU MỖI NGÀY - RONG RÊU | TÌNH KHÚC HẢI NGOẠI BẤT HỦ","duration":7436,"samplerate":"160 kbps"} Jul 21 22:01:09 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Jul 21 22:01:09 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:09 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:01:09 volumio volumio[955]: info: ControllerMpd::seek Jul 21 22:01:09 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:09 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 22:01:10 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:10 volumio volumio[955]: error: [2@0] {seek} Bad song index Jul 21 22:01:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:10 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:10 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:11 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:12 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:13 volumio ntpd[777]: Soliciting pool server 2001:c38:2000:180:d82a:1aff:fe38:f84b Jul 21 22:01:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:13 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:14 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::ClearQueue Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::stop Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::serviceStop Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::serviceStop Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:01:14 volumio volumio[955]: info: ControllerMpd::stop Jul 21 22:01:14 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::addQueueItems Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::addQueueItems Jul 21 22:01:14 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:14 volumio volumio[955]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:14 volumio volumio[955]: info: Exploding uri youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D in service youtube2 Jul 21 22:01:14 volumio volumio[955]: info: [youtube2-browse] explodeUri: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::play index 0 Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::stop Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::play index undefined Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:14 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:14 volumio volumio[955]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:14 volumio volumio[955]: info: sendMpdCommand stop took 111 milliseconds Jul 21 22:01:14 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:14 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:14 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:15 volumio wpa_supplicant[875]: RRM: Ignoring radio measurement request: Not RRM network Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 22:01:15 volumio volumio[955]: info: sendMpdCommand stop took 3 milliseconds Jul 21 22:01:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Jul 21 22:01:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:15 volumio volumio[955]: info: Jul 21 22:01:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:15 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:15 volumio volumio[955]: info: sendMpdCommand clear took 12 milliseconds Jul 21 22:01:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595674&ei=uiKdZpW9JpqjvcAPotWp6Aw&ip=14.169.21.223&id=o-ALwbN30tIAJtbjD5wYzcL4giMwIf8DKW4Z31eyelzAeK&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671KODhtrjl_EGvjBy0PxDQ3nFeCmIi0EZugebclIz2EaQjyhT0zO5FNX-FR7tq6qOjjiZQ&spc=NO7bAe1MG9-gf_zS2j8AiN_OKA4WJZSTtF1cKbbix58PHk2r_lCo9Tj5s2lIN0I&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=K6XItm1XAVS0bplWn8gpqEEQ&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=uNKIJhZRJhoqjA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRgIhAOgRApjNQ4kwzj_hIbRF2HLx4DbdS4jaanzIYGWEi5FiAiEAhy8GIIIF7Jt6Xw2N-YXG8Dj0F3NuAbGZ6xiG7qpdfcE%3D&sig=AJfQdSswRQIgW8C9h8RK4AXBf5E3nc3C-mECK5GHS6Qb98ZcBpRxUUwCIQCkaKrX66H9uwMTL2fWVayY13J_rZg2OinJPCQWkhl4iA%3D%3D&cver=2.20230104.01.00&t.YouTube" Jul 21 22:01:15 volumio volumio[955]: info: Jul 21 22:01:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:15 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:15 volumio volumio[955]: error: updateQueue error: null Jul 21 22:01:15 volumio volumio[955]: info: Jul 21 22:01:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:15 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:15 volumio volumio[955]: info: ------------------------------ 15ms Jul 21 22:01:15 volumio volumio[955]: info: sendMpdCommand addid "https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595674&ei=uiKdZpW9JpqjvcAPotWp6Aw&ip=14.169.21.223&id=o-ALwbN30tIAJtbjD5wYzcL4giMwIf8DKW4Z31eyelzAeK&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671KODhtrjl_EGvjBy0PxDQ3nFeCmIi0EZugebclIz2EaQjyhT0zO5FNX-FR7tq6qOjjiZQ&spc=NO7bAe1MG9-gf_zS2j8AiN_OKA4WJZSTtF1cKbbix58PHk2r_lCo9Tj5s2lIN0I&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=K6XItm1XAVS0bplWn8gpqEEQ&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=uNKIJhZRJhoqjA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRgIhAOgRApjNQ4kwzj_hIbRF2HLx4DbdS4jaanzIYGWEi5FiAiEAhy8GIIIF7Jt6Xw2N-YXG8Dj0F3NuAbGZ6xiG7qpdfcE%3D&sig=AJfQdSswRQIgW8C9h8RK4AXBf5E3nc3C-mECK5GHS6Qb98ZcBpRxUUwCIQCkaKrX66H9uwMTL2fWVayY13J_rZg2OinJPCQWkhl4iA%3D%3D&cver=2.20230104.01.00&t.YouTube" took 10 milliseconds Jul 21 22:01:15 volumio volumio[955]: info: ------------------------------ 9ms Jul 21 22:01:15 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:15 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:15 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:15 volumio volumio[955]: info: Jul 21 22:01:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:15 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:15 volumio volumio[955]: info: Jul 21 22:01:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:15 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:15 volumio volumio[955]: info: ------------------------------ 16ms Jul 21 22:01:15 volumio volumio[955]: info: ------------------------------ 5ms Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:01:15 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Jul 21 22:01:15 volumio volumio[955]: info: Jul 21 22:01:15 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:15 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:15 volumio volumio[955]: info: ------------------------------ 11ms Jul 21 22:01:15 volumio volumio[955]: info: sendMpdCommand play took 8 milliseconds Jul 21 22:01:15 volumio volumio[955]: info: ------------------------------ 7ms Jul 21 22:01:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:15 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:15 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::ClearQueue Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::stop Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 21 22:01:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::addQueueItems Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::addQueueItems Jul 21 22:01:15 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:15 volumio volumio[955]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:15 volumio volumio[955]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:15 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 21 22:01:15 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::play index 0 Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::stop Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::play index undefined Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:15 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:15 volumio volumio[955]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:15 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand stop took 3 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:16 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand status took 6 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand clear took 7 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand status took 4 milliseconds Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595675&ei=uyKdZuGLJ6iZvcAPsM-6mAw&ip=14.169.21.223&id=o-AKHydCZvGu0pr7_e9M-8fMb2JlJIFpVgAb0fFYEH5F5P&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671JV1gB0UO96gtQs8pTQ8o0_0MM_9WpYGBz1XfcJQiBCmQgrVTQTFtzMvA1ZZ_1xBKgxug&spc=NO7bATB219JooDiWE33Si6naquTqX_aJiUzWz461JbNcLNBdQPR_PpWJZojQN5Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xcaa1ObSf1xDy4afetHt1hgQ&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=YYCDRXFB5CD2vA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgWM9jp6VwW4FBpkTKBWrcTYSCVkCFUdz6p05ddfLWMS8CIQCx-2BOJlxKMpp4x8rVlw9PpUkUSwA22ttjkKNksdkJRA%3D%3D&sig=AJfQdSswRAIgHfF73zInTs7SMZ9aKq0ICy_UwYr3SQppz1fEh3HYwdMCIGDcyQTgPX6R7SfpZjVYGc9xmitN1tW-alyBW_TgcNrl&cver=2.20230104.01.00&t.YouTube" Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:16 volumio volumio[955]: info: ControllerMpd::pushState Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 27ms Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: error: updateQueue error: null Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 30ms Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand addid "https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595675&ei=uyKdZuGLJ6iZvcAPsM-6mAw&ip=14.169.21.223&id=o-AKHydCZvGu0pr7_e9M-8fMb2JlJIFpVgAb0fFYEH5F5P&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671JV1gB0UO96gtQs8pTQ8o0_0MM_9WpYGBz1XfcJQiBCmQgrVTQTFtzMvA1ZZ_1xBKgxug&spc=NO7bATB219JooDiWE33Si6naquTqX_aJiUzWz461JbNcLNBdQPR_PpWJZojQN5Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xcaa1ObSf1xDy4afetHt1hgQ&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=YYCDRXFB5CD2vA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgWM9jp6VwW4FBpkTKBWrcTYSCVkCFUdz6p05ddfLWMS8CIQCx-2BOJlxKMpp4x8rVlw9PpUkUSwA22ttjkKNksdkJRA%3D%3D&sig=AJfQdSswRAIgHfF73zInTs7SMZ9aKq0ICy_UwYr3SQppz1fEh3HYwdMCIGDcyQTgPX6R7SfpZjVYGc9xmitN1tW-alyBW_TgcNrl&cver=2.20230104.01.00&t.YouTube" took 24 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 7ms Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 21 22:01:16 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 21 22:01:16 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:16 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:16 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 45ms Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 22ms Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 12ms Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::ClearQueue Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::stop Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::addQueueItems Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::addQueueItems Jul 21 22:01:16 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:16 volumio volumio[955]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:16 volumio volumio[955]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::play index 0 Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::stop Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::play index undefined Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 41ms Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand play took 38 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 38ms Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand stop took 5 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:16 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand status took 6 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand clear took 6 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand status took 5 milliseconds Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595676&ei=vCKdZpPpE9abvcAPj5Py0Q4&ip=14.169.21.223&id=o-AO7qqhEl8PUlo7aIeMG5NlMLYk3-uCr9vemEG82EqibU&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671KeJgpVAg9R5mfwgtf2QrdFwWM2bpVEX2zIBFw9hLUkpPgZPJCnuQl5iSDcmYZZP42rWQ&spc=NO7bAV9-0vC3_cOaGv0kZodq5CsB81lC2ha0fqubwZCurk_YdTbT3yfH9aQaLSc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kyRuXoLH6-JkVP1sjr5vT_4Q&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=5SUT17fbvkdEKg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgPowqKfbwwKuw95-Fv6c4jpNU5VVTd6L6Wza57DqA-kgCID3--6uNtiQsZ2m78BW96VRRoF_egyQimcnyyJxqRjB8&sig=AJfQdSswRQIhALpP7VBciUrYRc1n7oKfmtKc5OKhC1-i91rvp-19pEVRAiAwh2NzWwzf5h_nkeuXlwYR--KM4linNb_-uzXusjFksQ%3D%3D&cver=2.20230104.01.00&t.YouTube" Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:16 volumio volumio[955]: info: ControllerMpd::pushState Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 21ms Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: error: updateQueue error: null Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 26ms Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand addid "https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595676&ei=vCKdZpPpE9abvcAPj5Py0Q4&ip=14.169.21.223&id=o-AO7qqhEl8PUlo7aIeMG5NlMLYk3-uCr9vemEG82EqibU&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671KeJgpVAg9R5mfwgtf2QrdFwWM2bpVEX2zIBFw9hLUkpPgZPJCnuQl5iSDcmYZZP42rWQ&spc=NO7bAV9-0vC3_cOaGv0kZodq5CsB81lC2ha0fqubwZCurk_YdTbT3yfH9aQaLSc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kyRuXoLH6-JkVP1sjr5vT_4Q&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=5SUT17fbvkdEKg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgPowqKfbwwKuw95-Fv6c4jpNU5VVTd6L6Wza57DqA-kgCID3--6uNtiQsZ2m78BW96VRRoF_egyQimcnyyJxqRjB8&sig=AJfQdSswRQIhALpP7VBciUrYRc1n7oKfmtKc5OKhC1-i91rvp-19pEVRAiAwh2NzWwzf5h_nkeuXlwYR--KM4linNb_-uzXusjFksQ%3D%3D&cver=2.20230104.01.00&t.YouTube" took 23 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 9ms Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 21 22:01:16 volumio volumio[955]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 21 22:01:16 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:16 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:16 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 38ms Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:16 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 24ms Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 16ms Jul 21 22:01:16 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:01:16 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Jul 21 22:01:16 volumio volumio[955]: info: Jul 21 22:01:16 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:16 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 21ms Jul 21 22:01:16 volumio volumio[955]: info: sendMpdCommand play took 19 milliseconds Jul 21 22:01:16 volumio volumio[955]: info: ------------------------------ 19ms Jul 21 22:01:16 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:16 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:17 volumio volumio[955]: info: Jul 21 22:01:17 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:17 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:17 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:17 volumio volumio[955]: info: Jul 21 22:01:17 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:17 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:17 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - ---- read samplerate from file: 48000 Jul 21 22:01:17 volumio volumio[955]: info: camilladsp stopping service pid 5042... Jul 21 22:01:17 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1) Jul 21 22:01:17 volumio volumio[955]: info: camilladsp service terminated, instance 1 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:01:17 volumio volumio[955]: info: camilladsp service started and running in background, instance 1 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - ---- read samplerate from file: 48000 Jul 21 22:01:17 volumio volumio[955]: info: camilladsp stopping service pid 5160... Jul 21 22:01:17 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1) Jul 21 22:01:17 volumio volumio[955]: info: camilladsp service terminated, instance 2 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:01:17 volumio volumio[955]: info: camilladsp service started and running in background, instance 2 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - ---- read samplerate from file: 48000 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - ---- read samplerate from file: 48000 Jul 21 22:01:17 volumio volumio[955]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 21 22:01:17 volumio volumio[955]: info: Jul 21 22:01:17 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:17 volumio volumio[955]: info: sendMpdCommand status took 203 milliseconds Jul 21 22:01:17 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:17 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:17 volumio volumio[955]: info: sendMpdCommand status took 202 milliseconds Jul 21 22:01:17 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:17 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 22:01:17 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:17 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 22:01:17 volumio volumio[955]: info: Jul 21 22:01:17 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:17 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:17 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:18 volumio volumio[955]: info: camilladsp spawned new process with pid 6536, instance 2, run: true Jul 21 22:01:18 volumio volumio[955]: info: sendMpdCommand status took 74 milliseconds Jul 21 22:01:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 74 milliseconds Jul 21 22:01:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 73 milliseconds Jul 21 22:01:18 volumio volumio[955]: info: sendMpdCommand status took 69 milliseconds Jul 21 22:01:18 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 22:01:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 21 22:01:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 21 22:01:18 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:18 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 22:01:18 volumio volumio[955]: info: ControllerMpd::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 21 22:01:18 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5245,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nhạc Nhẹ Test Loa Bậc Nhất Đây Rồi | Nghe Lách Tách Sướng Cho Đôi Tai","artist":"Nhạc Sống Thanh Ngân","album":"YouTube","uri":"https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595676&ei=vCKdZpPpE9abvcAPj5Py0Q4&ip=14.169.21.223&id=o-AO7qqhEl8PUlo7aIeMG5NlMLYk3-uCr9vemEG82EqibU&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671KeJgpVAg9R5mfwgtf2QrdFwWM2bpVEX2zIBFw9hLUkpPgZPJCnuQl5iSDcmYZZP42rWQ&spc=NO7bAV9-0vC3_cOaGv0kZodq5CsB81lC2ha0fqubwZCurk_YdTbT3yfH9aQaLSc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kyRuXoLH6-JkVP1sjr5vT_4Q&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=5SUT17fbvkdEKg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgPowqKfbwwKuw95-Fv6c4jpNU5VVTd6L6Wza57DqA-kgCID3--6uNtiQsZ2m78BW96VRRoF_egyQimcnyyJxqRjB8&sig=AJfQdSswRQIhALpP7VBciUrYRc1n7oKfmtKc5OKhC1-i91rvp-19pEVRAiAwh2NzWwzf5h_nkeuXlwYR--KM4linNb_-uzXusjFksQ%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Jul 21 22:01:18 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Jul 21 22:01:18 volumio volumio[955]: info: ControllerMpd::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 21 22:01:18 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5245,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nhạc Nhẹ Test Loa Bậc Nhất Đây Rồi | Nghe Lách Tách Sướng Cho Đôi Tai","artist":"Nhạc Sống Thanh Ngân","album":"YouTube","uri":"https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595676&ei=vCKdZpPpE9abvcAPj5Py0Q4&ip=14.169.21.223&id=o-AO7qqhEl8PUlo7aIeMG5NlMLYk3-uCr9vemEG82EqibU&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671KeJgpVAg9R5mfwgtf2QrdFwWM2bpVEX2zIBFw9hLUkpPgZPJCnuQl5iSDcmYZZP42rWQ&spc=NO7bAV9-0vC3_cOaGv0kZodq5CsB81lC2ha0fqubwZCurk_YdTbT3yfH9aQaLSc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kyRuXoLH6-JkVP1sjr5vT_4Q&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=5SUT17fbvkdEKg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgPowqKfbwwKuw95-Fv6c4jpNU5VVTd6L6Wza57DqA-kgCID3--6uNtiQsZ2m78BW96VRRoF_egyQimcnyyJxqRjB8&sig=AJfQdSswRQIhALpP7VBciUrYRc1n7oKfmtKc5OKhC1-i91rvp-19pEVRAiAwh2NzWwzf5h_nkeuXlwYR--KM4linNb_-uzXusjFksQ%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Jul 21 22:01:18 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 21 22:01:18 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:18 volumio volumio[955]: info: ------------------------------ 310ms Jul 21 22:01:18 volumio volumio[955]: info: ------------------------------ 326ms Jul 21 22:01:18 volumio volumio[955]: error: FusionDsp - WebSocket error: [object Object] Jul 21 22:01:18 volumio volumio[955]: error: FusionDsp - WebSocket error: [object Object] Jul 21 22:01:18 volumio volumio[955]: info: camilladsp spawned new process with pid 6543, instance 1, run: true Jul 21 22:01:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 111 milliseconds Jul 21 22:01:18 volumio volumio[955]: info: sendMpdCommand playlistinfo took 108 milliseconds Jul 21 22:01:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 21 22:01:18 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 21 22:01:18 volumio volumio[955]: info: ControllerMpd::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 21 22:01:18 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5245,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nhạc Nhẹ Test Loa Bậc Nhất Đây Rồi | Nghe Lách Tách Sướng Cho Đôi Tai","artist":"Nhạc Sống Thanh Ngân","album":"YouTube","uri":"https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595676&ei=vCKdZpPpE9abvcAPj5Py0Q4&ip=14.169.21.223&id=o-AO7qqhEl8PUlo7aIeMG5NlMLYk3-uCr9vemEG82EqibU&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671KeJgpVAg9R5mfwgtf2QrdFwWM2bpVEX2zIBFw9hLUkpPgZPJCnuQl5iSDcmYZZP42rWQ&spc=NO7bAV9-0vC3_cOaGv0kZodq5CsB81lC2ha0fqubwZCurk_YdTbT3yfH9aQaLSc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kyRuXoLH6-JkVP1sjr5vT_4Q&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=5SUT17fbvkdEKg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgPowqKfbwwKuw95-Fv6c4jpNU5VVTd6L6Wza57DqA-kgCID3--6uNtiQsZ2m78BW96VRRoF_egyQimcnyyJxqRjB8&sig=AJfQdSswRQIhALpP7VBciUrYRc1n7oKfmtKc5OKhC1-i91rvp-19pEVRAiAwh2NzWwzf5h_nkeuXlwYR--KM4linNb_-uzXusjFksQ%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Jul 21 22:01:18 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 21 22:01:18 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:18 volumio volumio[955]: info: ControllerMpd::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 21 22:01:18 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:18 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5245,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nhạc Nhẹ Test Loa Bậc Nhất Đây Rồi | Nghe Lách Tách Sướng Cho Đôi Tai","artist":"Nhạc Sống Thanh Ngân","album":"YouTube","uri":"https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595676&ei=vCKdZpPpE9abvcAPj5Py0Q4&ip=14.169.21.223&id=o-AO7qqhEl8PUlo7aIeMG5NlMLYk3-uCr9vemEG82EqibU&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1401250&siu=1&bui=AXc671KeJgpVAg9R5mfwgtf2QrdFwWM2bpVEX2zIBFw9hLUkpPgZPJCnuQl5iSDcmYZZP42rWQ&spc=NO7bAV9-0vC3_cOaGv0kZodq5CsB81lC2ha0fqubwZCurk_YdTbT3yfH9aQaLSc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=kyRuXoLH6-JkVP1sjr5vT_4Q&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573571&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=5SUT17fbvkdEKg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgPowqKfbwwKuw95-Fv6c4jpNU5VVTd6L6Wza57DqA-kgCID3--6uNtiQsZ2m78BW96VRRoF_egyQimcnyyJxqRjB8&sig=AJfQdSswRQIhALpP7VBciUrYRc1n7oKfmtKc5OKhC1-i91rvp-19pEVRAiAwh2NzWwzf5h_nkeuXlwYR--KM4linNb_-uzXusjFksQ%3D%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Jul 21 22:01:18 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 21 22:01:18 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:18 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:18 volumio volumio[955]: info: ------------------------------ 242ms Jul 21 22:01:18 volumio volumio[955]: info: ------------------------------ 223ms Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:18 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:18 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:18 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:18 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:18 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:18 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:19 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:20 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:20 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:20 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:20 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:23 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:23 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:23 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:23 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:23 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:23 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:24 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:26 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:27 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:28 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:29 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:30 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:32 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 21 22:01:32 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 21 22:01:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:32 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:33 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:34 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:34 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:34 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:34 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:35 volumio volumio[955]: info: FusionDsp - Stopping FusionDsp service Jul 21 22:01:35 volumio volumio[955]: info: camilladsp stopping service pid 6536... Jul 21 22:01:35 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1) Jul 21 22:01:35 volumio volumio[955]: info: camilladsp service terminated, instance 2 Jul 21 22:01:35 volumio volumio[955]: info: Disabling plugin fusiondsp Jul 21 22:01:35 volumio volumio[955]: info: Preparing to generate the ALSA configuration file Jul 21 22:01:35 volumio sudo[6902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jul 21 22:01:35 volumio sudo[6902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:35 volumio volumio[955]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 21 22:01:35 volumio volumio[955]: info: Reading ALSA contributions from plugins. Jul 21 22:01:35 volumio volumio[955]: info: Asound.conf file written Jul 21 22:01:35 volumio systemd[1]: Stopping FusionDsp Daemon... Jul 21 22:01:35 volumio volumio[5024]: Backend configuration: Jul 21 22:01:35 volumio volumio[5024]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jul 21 22:01:35 volumio volumio[5024]: active_config_txt: null Jul 21 22:01:35 volumio volumio[5024]: camilla_host: 127.0.0.1 Jul 21 22:01:35 volumio volumio[5024]: camilla_port: 9876 Jul 21 22:01:35 volumio volumio[5024]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jul 21 22:01:35 volumio volumio[5024]: config_dir: /data/configuration/audio_interface/fusiondsp Jul 21 22:01:35 volumio volumio[5024]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jul 21 22:01:35 volumio volumio[5024]: log_file: /tmp/camilladsp.log Jul 21 22:01:35 volumio volumio[5024]: on_get_active_config: null Jul 21 22:01:35 volumio volumio[5024]: on_set_active_config: null Jul 21 22:01:35 volumio volumio[5024]: port: 5011 Jul 21 22:01:35 volumio volumio[5024]: supported_capture_types: null Jul 21 22:01:35 volumio volumio[5024]: supported_playback_types: null Jul 21 22:01:35 volumio volumio[5024]: update_config_symlink: false Jul 21 22:01:35 volumio volumio[5024]: update_config_txt: false Jul 21 22:01:35 volumio volumio[5024]: ======== Running on http://0.0.0.0:5011 ======== Jul 21 22:01:35 volumio volumio[5024]: (Press CTRL+C to quit) Jul 21 22:01:35 volumio sudo[6905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 21 22:01:35 volumio sudo[6905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:35 volumio sudo[6905]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:35 volumio systemd[1]: fusiondsp.service: Succeeded. Jul 21 22:01:35 volumio systemd[1]: Stopped FusionDsp Daemon. Jul 21 22:01:35 volumio volumio[955]: info: Output device has changed, restarting MPD Jul 21 22:01:35 volumio sudo[6902]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:35 volumio sudo[6910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 21 22:01:35 volumio sudo[6910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:35 volumio sudo[6910]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:35 volumio volumio[955]: info: Output device has changed, restarting Shairport Sync Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 22:01:35 volumio sudo[6913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 21 22:01:35 volumio sudo[6913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:35 volumio systemd[1]: Stopping MPD OLED Plugin... Jul 21 22:01:35 volumio systemd[1]: mpd_oled_plugin.service: Main process exited, code=killed, status=15/TERM Jul 21 22:01:35 volumio volumio[955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 21 22:01:35 volumio volumio[955]: info: Done. Jul 21 22:01:35 volumio volumio[955]: info: MPD Permissions set Jul 21 22:01:35 volumio volumio[955]: info: FusionDsp - Reporting Fusion DSP Disabled Jul 21 22:01:35 volumio volumio[955]: info: Removing fusiondspeq DSP Signal Path Element Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:35 volumio volumio[955]: info: Starting Shairport Sync Jul 21 22:01:36 volumio systemd[1]: mpd_oled_plugin.service: Succeeded. Jul 21 22:01:36 volumio systemd[1]: Stopped MPD OLED Plugin. Jul 21 22:01:36 volumio systemd[1]: Stopping Music Player Daemon... Jul 21 22:01:36 volumio sudo[6923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 21 22:01:36 volumio sudo[6923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:36 volumio systemd[1]: mpd.service: Succeeded. Jul 21 22:01:36 volumio systemd[1]: Stopped Music Player Daemon. Jul 21 22:01:36 volumio systemd[1]: Starting Music Player Daemon... Jul 21 22:01:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 21 22:01:36 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 21 22:01:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 21 22:01:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 21 22:01:36 volumio sudo[6923]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:36 volumio volumio[955]: info: Shairport-Sync Started Jul 21 22:01:36 volumio volumio[955]: error: Upnp client error: Error: This socket has been ended by the other party Jul 21 22:01:36 volumio sudo[6925]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 21 22:01:36 volumio sudo[6925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:36 volumio sudo[6925]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:36 volumio volumio[955]: info: camilladsp spawned new process with pid 6934, instance 1, run: true Jul 21 22:01:36 volumio volumio[955]: info: camilladsp spawned new process with pid 6942, instance 1, run: true Jul 21 22:01:36 volumio volumio[955]: info: camilladsp spawned new process with pid 6949, instance 1, run: true Jul 21 22:01:36 volumio volumio[955]: info: camilladsp spawned new process with pid 6956, instance 1, run: true Jul 21 22:01:36 volumio volumio[955]: info: camilladsp spawned new process with pid 6963, instance 1, run: true Jul 21 22:01:36 volumio volumio[955]: info: camilladsp spawned new process with pid 6970, instance 1, run: true Jul 21 22:01:36 volumio volumio[955]: info: camilladsp spawned new process with pid 6977, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 6984, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 6991, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 6998, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 7005, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 7012, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 7019, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 7026, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 7033, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 7040, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 7047, instance 1, run: true Jul 21 22:01:37 volumio volumio[955]: info: camilladsp spawned new process with pid 7054, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: CoreCommandRouter::volumioPause Jul 21 22:01:38 volumio volumio[955]: info: CoreStateMachine::pause Jul 21 22:01:38 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Jul 21 22:01:38 volumio volumio[955]: info: CoreStateMachine::servicePause Jul 21 22:01:38 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:38 volumio volumio[955]: info: CoreCommandRouter::servicePause Jul 21 22:01:38 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:01:38 volumio volumio[955]: info: ControllerMpd::pause Jul 21 22:01:38 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand pause Jul 21 22:01:38 volumio mpd[6935]: Jul 21 22:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 21 22:01:38 volumio volumio[955]: error: MPD error: Error: This socket has been ended by the other party Jul 21 22:01:38 volumio volumio[955]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 21 22:01:38 volumio volumio[955]: error: MPD error: Error: This socket has been ended by the other party Jul 21 22:01:38 volumio volumio[955]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 21 22:01:38 volumio volumio[955]: error: MPD error: Error: This socket has been ended by the other party Jul 21 22:01:38 volumio volumio[955]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 21 22:01:38 volumio systemd[1]: Started Music Player Daemon. Jul 21 22:01:38 volumio sudo[6913]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:38 volumio systemd[1]: Started MPD OLED Plugin. Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7064, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: error: updateQueue error: null Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7072, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7079, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7091, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7102, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7109, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7116, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7123, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:38 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7136, instance 1, run: true Jul 21 22:01:38 volumio volumio[955]: info: camilladsp spawned new process with pid 7143, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7150, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7157, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7167, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7177, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7186, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7193, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7200, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7213, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7220, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7227, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: camilladsp spawned new process with pid 7234, instance 1, run: true Jul 21 22:01:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:39 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7245, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7254, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7261, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7268, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7275, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7293, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7300, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7307, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7314, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:40 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7324, instance 1, run: true Jul 21 22:01:40 volumio volumio[955]: info: camilladsp spawned new process with pid 7334, instance 1, run: true Jul 21 22:01:41 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::ClearQueue Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::stop Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::stPlaybackTimer Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::serviceStop Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::serviceStop Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:01:41 volumio volumio[955]: info: ControllerMpd::stop Jul 21 22:01:41 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::addQueueItems Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::addQueueItems Jul 21 22:01:41 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:41 volumio volumio[955]: info: Adding Item to queue: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:41 volumio volumio[955]: info: Using cached record of: youtube2/video@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::saveQueue Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::play index 0 Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::stop Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::play index undefined Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:41 volumio volumio[955]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22Nh%E1%BA%A1c%20Nh%E1%BA%B9%20Test%20Loa%20B%E1%BA%ADc%20Nh%E1%BA%A5t%20%C4%90%C3%A2y%20R%E1%BB%93i%20%7C%20Nghe%20L%C3%A1ch%20T%C3%A1ch%20S%C6%B0%E1%BB%9Bng%20Cho%20%C4%90%C3%B4i%20Tai%22%2C%22artist%22%3A%22Nh%E1%BA%A1c%20S%E1%BB%91ng%20Thanh%20Ng%C3%A2n%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSQyWnfTONLA%2Fhq720.jpg%3Fsqp%3D-oaymwEcCNAFEJQDSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLBzkw9juKMEbNO8uZuneCasf3dSoA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SQyWnfTONLA%22%7D%7D%7D Jul 21 22:01:41 volumio volumio[955]: info: sendMpdCommand stop took 44 milliseconds Jul 21 22:01:41 volumio volumio[955]: info: camilladsp spawned new process with pid 7341, instance 1, run: true Jul 21 22:01:41 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:41 volumio volumio[955]: info: camilladsp spawned new process with pid 7348, instance 1, run: true Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:41 volumio volumio[955]: info: camilladsp spawned new process with pid 7360, instance 1, run: true Jul 21 22:01:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:41 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jul 21 22:01:41 volumio volumio[955]: info: sendMpdCommand stop took 2 milliseconds Jul 21 22:01:41 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Jul 21 22:01:41 volumio volumio[955]: info: Jul 21 22:01:41 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:41 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:41 volumio volumio[955]: info: sendMpdCommand clear took 3 milliseconds Jul 21 22:01:41 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595701&ei=1SKdZvStD-WfvcAP9Pq08Ao&ip=14.169.21.223&id=o-AOiFca75sxH4iR-BO-M9pQErYUNtG8hL319JspuQzIrh&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1335000&siu=1&bui=AXc671J1xNSM0NYOkRYdwPD3HjKVuoTA9c4lNL12o0orr-_MTpdPeogZEFfc9vj3yW6hnNXsVA&spc=NO7bAVXz84Ho52E0_uzS3k4S7OBcIf8K9JGaZKzBBIM7_1bJ88bn9Hgnt0mLGjk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=N2KWdgUwuHKMpsH2u-RG6ckQ&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573810&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=QgqxHha9YFeEag&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgBY1ixvkuH5Jzj6Y_RN4DLYnqIsWSHfF685h6n4VqMnYCIQDhh1VwbV-BNfq3h6MvNlcI5P_hgz_7lUgbeOFCJH6VNA%3D%3D&sig=AJfQdSswRgIhAN1uulbV3NuAaL4kqcpSV7pBBRvn3mlPPGH5IrMeiOOXAiEA7NSz4P_TvIus1hkHYzu_IOTXrEh9tt2XTelnPrAfI4o%3D&cver=2.20230104.01.00&t.YouTube" Jul 21 22:01:41 volumio volumio[955]: info: camilladsp spawned new process with pid 7370, instance 1, run: true Jul 21 22:01:41 volumio volumio[955]: error: updateQueue error: null Jul 21 22:01:41 volumio volumio[955]: info: Jul 21 22:01:41 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:41 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:41 volumio volumio[955]: info: ------------------------------ 57ms Jul 21 22:01:41 volumio volumio[955]: info: sendMpdCommand addid "https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595701&ei=1SKdZvStD-WfvcAP9Pq08Ao&ip=14.169.21.223&id=o-AOiFca75sxH4iR-BO-M9pQErYUNtG8hL319JspuQzIrh&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1335000&siu=1&bui=AXc671J1xNSM0NYOkRYdwPD3HjKVuoTA9c4lNL12o0orr-_MTpdPeogZEFfc9vj3yW6hnNXsVA&spc=NO7bAVXz84Ho52E0_uzS3k4S7OBcIf8K9JGaZKzBBIM7_1bJ88bn9Hgnt0mLGjk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=N2KWdgUwuHKMpsH2u-RG6ckQ&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573810&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=QgqxHha9YFeEag&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgBY1ixvkuH5Jzj6Y_RN4DLYnqIsWSHfF685h6n4VqMnYCIQDhh1VwbV-BNfq3h6MvNlcI5P_hgz_7lUgbeOFCJH6VNA%3D%3D&sig=AJfQdSswRgIhAN1uulbV3NuAaL4kqcpSV7pBBRvn3mlPPGH5IrMeiOOXAiEA7NSz4P_TvIus1hkHYzu_IOTXrEh9tt2XTelnPrAfI4o%3D&cver=2.20230104.01.00&t.YouTube" took 54 milliseconds Jul 21 22:01:41 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:41 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:41 volumio volumio[955]: verbose: MPD COMMAND [object Object] Jul 21 22:01:41 volumio volumio[955]: info: Jul 21 22:01:41 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jul 21 22:01:41 volumio volumio[955]: info: Ignoring MPD Status Update Jul 21 22:01:41 volumio volumio[955]: info: ------------------------------ 14ms Jul 21 22:01:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 22:01:41 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Jul 21 22:01:41 volumio volumio[955]: info: ------------------------------ 13ms Jul 21 22:01:41 volumio volumio[955]: info: sendMpdCommand play took 9 milliseconds Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:41 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:41 volumio volumio[955]: info: camilladsp spawned new process with pid 7382, instance 1, run: true Jul 21 22:01:41 volumio volumio[955]: info: camilladsp spawned new process with pid 7393, instance 1, run: true Jul 21 22:01:41 volumio volumio[955]: info: camilladsp spawned new process with pid 7400, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7407, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7416, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7427, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7434, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7441, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7448, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7463, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7470, instance 1, run: true Jul 21 22:01:42 volumio volumio[955]: info: Jul 21 22:01:42 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:42 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:42 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:42 volumio volumio[955]: info: Jul 21 22:01:42 volumio volumio[955]: ---------------------------- MPD announces state update: player Jul 21 22:01:42 volumio volumio[955]: info: sendMpdCommand status took 60 milliseconds Jul 21 22:01:42 volumio volumio[955]: info: ControllerMpd::getState Jul 21 22:01:42 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jul 21 22:01:42 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:42 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 22:01:42 volumio volumio[955]: info: sendMpdCommand status took 3 milliseconds Jul 21 22:01:42 volumio volumio[955]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 21 22:01:42 volumio volumio[955]: verbose: ControllerMpd::parseState Jul 21 22:01:42 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 22:01:42 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 21 22:01:42 volumio volumio[955]: info: ControllerMpd::pushState Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 21 22:01:42 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:42 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5245,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nhạc Nhẹ Test Loa Bậc Nhất Đây Rồi | Nghe Lách Tách Sướng Cho Đôi Tai","artist":"Nhạc Sống Thanh Ngân","album":"YouTube","uri":"https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595701&ei=1SKdZvStD-WfvcAP9Pq08Ao&ip=14.169.21.223&id=o-AOiFca75sxH4iR-BO-M9pQErYUNtG8hL319JspuQzIrh&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1335000&siu=1&bui=AXc671J1xNSM0NYOkRYdwPD3HjKVuoTA9c4lNL12o0orr-_MTpdPeogZEFfc9vj3yW6hnNXsVA&spc=NO7bAVXz84Ho52E0_uzS3k4S7OBcIf8K9JGaZKzBBIM7_1bJ88bn9Hgnt0mLGjk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=N2KWdgUwuHKMpsH2u-RG6ckQ&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573810&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=QgqxHha9YFeEag&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgBY1ixvkuH5Jzj6Y_RN4DLYnqIsWSHfF685h6n4VqMnYCIQDhh1VwbV-BNfq3h6MvNlcI5P_hgz_7lUgbeOFCJH6VNA%3D%3D&sig=AJfQdSswRgIhAN1uulbV3NuAaL4kqcpSV7pBBRvn3mlPPGH5IrMeiOOXAiEA7NSz4P_TvIus1hkHYzu_IOTXrEh9tt2XTelnPrAfI4o%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Jul 21 22:01:42 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 21 22:01:42 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 21 22:01:42 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Jul 21 22:01:42 volumio volumio[955]: info: ------------------------------ 71ms Jul 21 22:01:42 volumio volumio[955]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 21 22:01:42 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jul 21 22:01:42 volumio volumio[955]: info: ControllerMpd::pushState Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jul 21 22:01:42 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jul 21 22:01:42 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":5245,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Nhạc Nhẹ Test Loa Bậc Nhất Đây Rồi | Nghe Lách Tách Sướng Cho Đôi Tai","artist":"Nhạc Sống Thanh Ngân","album":"YouTube","uri":"https://rr1---sn-8qj-nbo6r.googlevideo.com/videoplayback?expire=1721595701&ei=1SKdZvStD-WfvcAP9Pq08Ao&ip=14.169.21.223&id=o-AOiFca75sxH4iR-BO-M9pQErYUNtG8hL319JspuQzIrh&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=wj&mm=31%2C29&mn=sn-8qj-nbo6r%2Csn-8qj-nbole&ms=au%2Crdu&mv=m&mvi=1&pl=27&initcwndbps=1335000&siu=1&bui=AXc671J1xNSM0NYOkRYdwPD3HjKVuoTA9c4lNL12o0orr-_MTpdPeogZEFfc9vj3yW6hnNXsVA&spc=NO7bAVXz84Ho52E0_uzS3k4S7OBcIf8K9JGaZKzBBIM7_1bJ88bn9Hgnt0mLGjk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=N2KWdgUwuHKMpsH2u-RG6ckQ&rqh=1&gir=yes&clen=98127659&dur=5245.101&lmt=1632006217534730&mt=1721573810&fvip=6&keepalive=yes&c=WEB&sefc=1&txp=5532434&n=QgqxHha9YFeEag&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgBY1ixvkuH5Jzj6Y_RN4DLYnqIsWSHfF685h6n4VqMnYCIQDhh1VwbV-BNfq3h6MvNlcI5P_hgz_7lUgbeOFCJH6VNA%3D%3D&sig=AJfQdSswRgIhAN1uulbV3NuAaL4kqcpSV7pBBRvn3mlPPGH5IrMeiOOXAiEA7NSz4P_TvIus1hkHYzu_IOTXrEh9tt2XTelnPrAfI4o%3D&cver=2.20230104.01.00&t.YouTube","trackType":"YouTube"} Jul 21 22:01:42 volumio volumio[955]: verbose: CURRENT POSITION 0 Jul 21 22:01:42 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jul 21 22:01:42 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus play Jul 21 22:01:42 volumio volumio[955]: info: Received an update from plugin. extracting info from payload Jul 21 22:01:42 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:42 volumio volumio[955]: info: CoreStateMachine::pushState Jul 21 22:01:42 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jul 21 22:01:42 volumio volumio[955]: info: ------------------------------ 46ms Jul 21 22:01:42 volumio volumio[955]: info: camilladsp spawned new process with pid 7480, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:43 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:43 volumio volumio[955]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7491, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7500, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7507, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7514, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7524, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7534, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7541, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7550, instance 1, run: true Jul 21 22:01:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:43 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:43 volumio volumio[955]: info: camilladsp spawned new process with pid 7561, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7570, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7577, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7584, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:44 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7596, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7604, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7611, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7618, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:44 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7630, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7640, instance 1, run: true Jul 21 22:01:44 volumio volumio[955]: info: camilladsp spawned new process with pid 7647, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7654, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7667, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7674, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7681, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7689, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7701, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7709, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7716, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7725, instance 1, run: true Jul 21 22:01:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:45 volumio volumio[955]: info: camilladsp spawned new process with pid 7737, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7745, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7752, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7759, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7772, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7779, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7786, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7793, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:46 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7806, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7815, instance 1, run: true Jul 21 22:01:46 volumio volumio[955]: info: camilladsp spawned new process with pid 7822, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7829, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7840, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7849, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7856, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7863, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7875, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7883, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7890, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: camilladsp spawned new process with pid 7899, instance 1, run: true Jul 21 22:01:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Jul 21 22:01:47 volumio volumio[955]: info: [youtube2-browse] browseUri: youtube2/generic@endpoint:o=%7B%22type%22%3A%22browse%22%2C%22payload%22%3A%7B%22browseId%22%3A%22FEwhat_to_watch%22%7D%7D Jul 21 22:01:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7909, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7919, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7926, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7933, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7943, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7953, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7960, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7967, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:48 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7978, instance 1, run: true Jul 21 22:01:48 volumio volumio[955]: info: camilladsp spawned new process with pid 7989, instance 1, run: true Jul 21 22:01:49 volumio volumio[955]: info: camilladsp spawned new process with pid 7996, instance 1, run: true Jul 21 22:01:49 volumio volumio[955]: info: camilladsp spawned new process with pid 8003, instance 1, run: true Jul 21 22:01:49 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:49 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:49 volumio volumio[955]: info: camilladsp spawned new process with pid 8016, instance 1, run: true Jul 21 22:01:49 volumio volumio[955]: info: camilladsp spawned new process with pid 8023, instance 1, run: true Jul 21 22:01:49 volumio volumio[955]: info: camilladsp spawned new process with pid 8030, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8039, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:50 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:50 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:50 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:50 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:50 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:50 volumio volumio[955]: info: Preload queue cleared Jul 21 22:01:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8050, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8059, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8066, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8073, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8085, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8093, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8100, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: camilladsp spawned new process with pid 8107, instance 1, run: true Jul 21 22:01:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:50 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8119, instance 1, run: true Jul 21 22:01:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8127, instance 1, run: true Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8136, instance 1, run: true Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8143, instance 1, run: true Jul 21 22:01:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8155, instance 1, run: true Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8163, instance 1, run: true Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8170, instance 1, run: true Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8177, instance 1, run: true Jul 21 22:01:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:51 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:51 volumio volumio[955]: info: camilladsp spawned new process with pid 8190, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8197, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8204, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 21 22:01:52 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8211, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:52 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8225, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8233, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8240, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8247, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:52 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8258, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8267, instance 1, run: true Jul 21 22:01:52 volumio volumio[955]: info: camilladsp spawned new process with pid 8274, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8281, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8291, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8301, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8310, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8317, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8327, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8337, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8344, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: camilladsp spawned new process with pid 8351, instance 1, run: true Jul 21 22:01:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:53 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8362, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8371, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8378, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8385, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8397, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8407, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8414, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8421, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:54 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8431, instance 1, run: true Jul 21 22:01:54 volumio volumio[955]: info: camilladsp spawned new process with pid 8441, instance 1, run: true Jul 21 22:01:55 volumio volumio[955]: info: camilladsp spawned new process with pid 8448, instance 1, run: true Jul 21 22:01:55 volumio volumio[955]: info: camilladsp spawned new process with pid 8455, instance 1, run: true Jul 21 22:01:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:55 volumio volumio[955]: info: camilladsp spawned new process with pid 8466, instance 1, run: true Jul 21 22:01:55 volumio volumio[955]: info: camilladsp spawned new process with pid 8475, instance 1, run: true Jul 21 22:01:55 volumio volumio[955]: info: camilladsp spawned new process with pid 8482, instance 1, run: true Jul 21 22:01:55 volumio volumio[955]: info: camilladsp spawned new process with pid 8491, instance 1, run: true Jul 21 22:01:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jul 21 22:01:55 volumio volumio[955]: info: camilladsp spawned new process with pid 8503, instance 1, run: true Jul 21 22:01:55 volumio volumio[955]: info: Starting Uninstall of plugin audio_interface - fusiondsp Jul 21 22:01:55 volumio volumio[955]: info: Uninstalling plugin fusiondsp Jul 21 22:01:55 volumio volumio[955]: info: Disabling plugin fusiondsp Jul 21 22:01:55 volumio volumio[955]: info: Preparing to generate the ALSA configuration file Jul 21 22:01:55 volumio volumio[955]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 21 22:01:55 volumio volumio[955]: info: Reading ALSA contributions from plugins. Jul 21 22:01:55 volumio volumio[955]: info: Asound.conf file unchanged, so no further update is needed Jul 21 22:01:55 volumio volumio[955]: info: Output device has changed, restarting MPD Jul 21 22:01:55 volumio sudo[8512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 21 22:01:55 volumio sudo[8512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:55 volumio sudo[8512]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:55 volumio volumio[955]: info: Output device has changed, restarting Shairport Sync Jul 21 22:01:55 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:55 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 22:01:55 volumio sudo[8515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 21 22:01:55 volumio sudo[8515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:55 volumio systemd[1]: Stopping MPD OLED Plugin... Jul 21 22:01:55 volumio systemd[1]: mpd_oled_plugin.service: Main process exited, code=killed, status=15/TERM Jul 21 22:01:55 volumio volumio[955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 21 22:01:55 volumio volumio[955]: info: Checking if uninstall.sh is present Jul 21 22:01:55 volumio volumio[955]: info: Executing uninstall.sh Jul 21 22:01:55 volumio volumio[955]: info: MPD Permissions set Jul 21 22:01:56 volumio volumio[955]: info: camilladsp spawned new process with pid 8523, instance 1, run: true Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio systemd[1]: mpd_oled_plugin.service: Succeeded. Jul 21 22:01:56 volumio systemd[1]: Stopped MPD OLED Plugin. Jul 21 22:01:56 volumio systemd[1]: Stopping Music Player Daemon... Jul 21 22:01:56 volumio sudo[8522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Jul 21 22:01:56 volumio sudo[8522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 22:01:56 volumio volumio[955]: info: Starting Shairport Sync Jul 21 22:01:56 volumio systemd[1]: mpd.service: Succeeded. Jul 21 22:01:56 volumio systemd[1]: Stopped Music Player Daemon. Jul 21 22:01:56 volumio systemd[1]: Starting Music Player Daemon... Jul 21 22:01:56 volumio sudo[8539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 21 22:01:56 volumio sudo[8539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:56 volumio volumio[955]: info: camilladsp spawned new process with pid 8540, instance 1, run: true Jul 21 22:01:56 volumio sudo[8548]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/multi-user.target.wants/fusiondsp.service Jul 21 22:01:56 volumio sudo[8548]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 21 22:01:56 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 21 22:01:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 21 22:01:56 volumio sudo[8548]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:56 volumio sudo[8522]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 21 22:01:56 volumio sudo[8539]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:56 volumio volumio[955]: info: camilladsp spawned new process with pid 8551, instance 1, run: true Jul 21 22:01:56 volumio volumio[955]: info: Shairport-Sync Started Jul 21 22:01:56 volumio volumio[955]: info: Uninstall script completed Jul 21 22:01:56 volumio volumio[955]: info: Removing plugin fusiondsp from configuration Jul 21 22:01:56 volumio sudo[8538]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 21 22:01:56 volumio sudo[8538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:56 volumio sudo[8538]: pam_unix(sudo:session): session closed for user root Jul 21 22:01:56 volumio volumio[955]: info: Successfully removed fusiondsp configuration files Jul 21 22:01:56 volumio volumio[955]: info: Plugin folders cleanup Jul 21 22:01:56 volumio volumio[955]: info: Scanning into folder /volumio/app/plugins/ Jul 21 22:01:56 volumio volumio[955]: info: Scanning category audio_interface Jul 21 22:01:56 volumio volumio[955]: info: Scanning category miscellanea Jul 21 22:01:56 volumio volumio[955]: info: Scanning category music_service Jul 21 22:01:56 volumio volumio[955]: info: Scanning category plugins.json Jul 21 22:01:56 volumio volumio[955]: info: Scanning category system_controller Jul 21 22:01:56 volumio volumio[955]: info: Scanning category user_interface Jul 21 22:01:56 volumio volumio[955]: info: Scanning into folder /data/plugins/ Jul 21 22:01:56 volumio volumio[955]: info: Scanning category audio_interface Jul 21 22:01:56 volumio volumio[955]: info: Cleaning folder for fusiondsp Jul 21 22:01:57 volumio volumio[955]: info: Scanning category music_service Jul 21 22:01:57 volumio volumio[955]: info: Scanning category system_hardware Jul 21 22:01:57 volumio volumio[955]: info: Scanning category user_interface Jul 21 22:01:57 volumio volumio[955]: info: Plugin folders cleanup completed Jul 21 22:01:57 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 21 22:01:57 volumio volumio[955]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.0.5","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.2.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.1.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MPD OLED","name":"mpd_oled","category":"system_hardware","version":"1.1.3","icon":"fa-television","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.3","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Jul 21 22:01:57 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 21 22:01:57 volumio volumio[955]: error: Upnp client error: Error: This socket has been ended by the other party Jul 21 22:01:57 volumio volumio[955]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 21 22:01:57 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 22:01:57 volumio volumio[955]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 21 22:01:57 volumio volumio[955]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 21 22:01:57 volumio volumio[955]: at onErrorNT (internal/child_process.js:465:16) Jul 21 22:01:57 volumio volumio[955]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 21 22:01:57 volumio volumio[955]: errno: -2, Jul 21 22:01:57 volumio volumio[955]: code: 'ENOENT', Jul 21 22:01:57 volumio volumio[955]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 21 22:01:57 volumio volumio[955]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 21 22:01:57 volumio volumio[955]: spawnargs: [ Jul 21 22:01:57 volumio volumio[955]: '-p', Jul 21 22:01:57 volumio volumio[955]: 9876, Jul 21 22:01:57 volumio volumio[955]: '-o', Jul 21 22:01:57 volumio volumio[955]: '/tmp/camilladsp.log', Jul 21 22:01:57 volumio volumio[955]: '-l', Jul 21 22:01:57 volumio volumio[955]: 'warn', Jul 21 22:01:57 volumio volumio[955]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 21 22:01:57 volumio volumio[955]: ] Jul 21 22:01:57 volumio volumio[955]: } Jul 21 22:01:57 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 22:01:57 volumio sudo[8576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-21 22:00 Jul 21 22:01:57 volumio sudo[8576]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 22:01:58 volumio mpd[8562]: Jul 21 22:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"