-- Logs begin at Fri 2025-03-07 18:59:35 CET, end at Fri 2025-03-07 19:17:38 CET. -- Mar 07 19:16:00 loovolumio volumio[4945]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 07 19:16:00 loovolumio volumio[4945]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 07 19:16:00 loovolumio volumio[4945]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 07 19:16:00 loovolumio volumio[4945]: info: Reading ALSA contributions from plugins. Mar 07 19:16:00 loovolumio volumio[4945]: info: minidlna: Found miniDLNA version 1.2.1 Mar 07 19:16:00 loovolumio volumio[4945]: info: minidlna: Starting minidlna.service Mar 07 19:16:00 loovolumio volumio[4945]: info: MPD Permissions set Mar 07 19:16:00 loovolumio volumio[4945]: info: MPD Permissions set Mar 07 19:16:00 loovolumio volumio[4945]: info: Volumio called home Mar 07 19:16:00 loovolumio sudo[5271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Mar 07 19:16:00 loovolumio sudo[5271]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:00 loovolumio sudo[5271]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:00 loovolumio volumio[4945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 07 19:16:00 loovolumio volumio[4945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 07 19:16:00 loovolumio volumio[4945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:00 loovolumio sudo[5254]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:00 loovolumio volumio[4945]: info: minidlna: systemctl start minidlna.service succeeded. Mar 07 19:16:00 loovolumio volumio[4945]: info: Starting Shairport Sync Mar 07 19:16:00 loovolumio volumio[4945]: info: Starting Shairport Sync Mar 07 19:16:00 loovolumio sudo[5288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 19:16:00 loovolumio sudo[5288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:00 loovolumio volumio[4945]: info: Starting Shairport Sync Mar 07 19:16:00 loovolumio sudo[5286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Mar 07 19:16:00 loovolumio sudo[5286]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:00 loovolumio sudo[5290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 19:16:00 loovolumio sudo[5290]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:00 loovolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 19:16:00 loovolumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 19:16:00 loovolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:00 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetState Mar 07 19:16:00 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:00 loovolumio sudo[5295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 19:16:00 loovolumio sudo[5295]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:00 loovolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:01 loovolumio sudo[5288]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 19:16:01 loovolumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 19:16:01 loovolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:01 loovolumio volumio[4945]: info: Asound.conf file unchanged, so no further update is needed Mar 07 19:16:01 loovolumio volumio[4945]: info: Output device has changed, restarting MPD Mar 07 19:16:01 loovolumio volumio[4945]: info: Output device has changed, restarting Shairport Sync Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:01 loovolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:01 loovolumio sudo[5305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 07 19:16:01 loovolumio sudo[5305]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:01 loovolumio sudo[5305]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio sudo[5290]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio sudo[5295]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio sudo[5308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 07 19:16:01 loovolumio sudo[5308]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:01 loovolumio mpd[5225]: Mar 07 19:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 07 19:16:01 loovolumio systemd[1]: Started Music Player Daemon. Mar 07 19:16:01 loovolumio sudo[5200]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio sudo[5191]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio systemd[1]: Stopping Music Player Daemon... Mar 07 19:16:01 loovolumio volumio[4945]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 19:16:01 loovolumio volumio[4945]: info: Preparing to generate the ALSA configuration file Mar 07 19:16:01 loovolumio systemd[1]: mpd.service: Succeeded. Mar 07 19:16:01 loovolumio systemd[1]: Stopped Music Player Daemon. Mar 07 19:16:01 loovolumio volumio[4945]: info: Shairport-Sync Started Mar 07 19:16:01 loovolumio volumio[4945]: Error adding Membership: Error: addMembership EINVAL Mar 07 19:16:01 loovolumio volumio[4945]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 07 19:16:01 loovolumio volumio[4945]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 07 19:16:01 loovolumio volumio[4945]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 07 19:16:01 loovolumio volumio[4945]: info: Reading ALSA contributions from plugins. Mar 07 19:16:01 loovolumio volumio[4945]: info: MPD Permissions set Mar 07 19:16:01 loovolumio systemd[1]: Starting Music Player Daemon... Mar 07 19:16:01 loovolumio volumio[4945]: info: Completed starting Core Plugins Mar 07 19:16:01 loovolumio volumio[4945]: info: ------------------------------------------- Mar 07 19:16:01 loovolumio volumio[4945]: info: ----- MyVolumio plugins startup ---- Mar 07 19:16:01 loovolumio volumio[4945]: info: ------------------------------------------- Mar 07 19:16:01 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 07 19:16:01 loovolumio volumio[4945]: info: Shairport-Sync Started Mar 07 19:16:01 loovolumio volumio[4945]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 07 19:16:01 loovolumio volumio[4945]: 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 Mar 07 19:16:01 loovolumio volumio[4945]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 07 19:16:01 loovolumio volumio[4945]: info: camilladsp spawned new process with pid 5328, instance 1, run: true Mar 07 19:16:01 loovolumio volumio[4945]: info: camilladsp service started and running in background, instance 1 Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio sudo[5319]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 07 19:16:01 loovolumio sudo[5319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:01 loovolumio sudo[5319]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio volumio[4945]: cannot open device 'volumioHw': Device or resource busy Mar 07 19:16:01 loovolumio volumio[4945]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Mar 07 19:16:01 loovolumio volumio[4945]: cannot open device 'volumioHw': Device or resource busy Mar 07 19:16:01 loovolumio volumio[4945]: info: FusionDsp loaded Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 19:16:01 loovolumio volumio[4945]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 07 19:16:01 loovolumio volumio[4945]: info: Adding Signal Path Element [object Object] Mar 07 19:16:01 loovolumio volumio[4945]: info: Adding fusiondspeq DSP Signal Path Element Mar 07 19:16:01 loovolumio volumio[4945]: info: FusionDsp - ---- installed callbackRead Mar 07 19:16:01 loovolumio volumio[4945]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 07 19:16:01 loovolumio sudo[5341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 07 19:16:01 loovolumio sudo[5341]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: error: Cannot initialize MPD Connection: MPD is not running Mar 07 19:16:01 loovolumio sudo[5341]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio volumio[4945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 07 19:16:01 loovolumio volumio[4945]: info: Starting Shairport Sync Mar 07 19:16:01 loovolumio volumio[4945]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 07 19:16:01 loovolumio sudo[5349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 19:16:01 loovolumio sudo[5349]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:01 loovolumio volumio[4945]: info: Asound.conf file unchanged, so no further update is needed Mar 07 19:16:01 loovolumio volumio[4945]: info: Output device has changed, restarting MPD Mar 07 19:16:01 loovolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 19:16:01 loovolumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 19:16:01 loovolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:01 loovolumio sudo[5352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 07 19:16:01 loovolumio sudo[5352]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:01 loovolumio volumio[4945]: info: Output device has changed, restarting Shairport Sync Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:01 loovolumio sudo[5352]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio sudo[5356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 07 19:16:01 loovolumio sudo[5356]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:01 loovolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:01 loovolumio sudo[5349]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:01 loovolumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 07 19:16:01 loovolumio systemd[1]: mpd.service: Succeeded. Mar 07 19:16:01 loovolumio systemd[1]: Stopped Music Player Daemon. Mar 07 19:16:01 loovolumio systemd[1]: Starting Music Player Daemon... Mar 07 19:16:01 loovolumio volumio[4945]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 19:16:01 loovolumio volumio[4945]: info: Preparing to generate the ALSA configuration file Mar 07 19:16:01 loovolumio volumio[4945]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 07 19:16:01 loovolumio volumio[4945]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 07 19:16:01 loovolumio volumio[4945]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 07 19:16:01 loovolumio volumio[4945]: info: Reading ALSA contributions from plugins. Mar 07 19:16:01 loovolumio volumio[4945]: info: MPD Permissions set Mar 07 19:16:01 loovolumio volumio[4945]: info: Shairport-Sync Started Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:01 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:02 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:02 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:02 loovolumio volumio[4945]: info: Starting Shairport Sync Mar 07 19:16:02 loovolumio sudo[5362]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 07 19:16:02 loovolumio sudo[5362]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:02 loovolumio volumio[4945]: info: Asound.conf file unchanged, so no further update is needed Mar 07 19:16:02 loovolumio volumio[4945]: info: Output device has changed, restarting MPD Mar 07 19:16:02 loovolumio sudo[5362]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:02 loovolumio sudo[5373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 19:16:02 loovolumio sudo[5373]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:02 loovolumio volumio[4945]: info: Output device has changed, restarting Shairport Sync Mar 07 19:16:02 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:02 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:02 loovolumio sudo[5376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 07 19:16:02 loovolumio sudo[5376]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:02 loovolumio sudo[5376]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:02 loovolumio sudo[5379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 07 19:16:02 loovolumio sudo[5379]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:02 loovolumio volumio[4945]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 19:16:02 loovolumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 07 19:16:02 loovolumio systemd[1]: mpd.service: Succeeded. Mar 07 19:16:02 loovolumio systemd[1]: Stopped Music Player Daemon. Mar 07 19:16:02 loovolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 19:16:02 loovolumio systemd[1]: Starting Music Player Daemon... Mar 07 19:16:02 loovolumio volumio[4945]: info: MPD Permissions set Mar 07 19:16:02 loovolumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 19:16:02 loovolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:02 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:02 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:02 loovolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:02 loovolumio sudo[5373]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:02 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:02 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:02 loovolumio volumio[4945]: info: Shairport-Sync Started Mar 07 19:16:02 loovolumio volumio[4945]: info: Starting Shairport Sync Mar 07 19:16:02 loovolumio sudo[5396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 19:16:02 loovolumio sudo[5396]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:02 loovolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 19:16:02 loovolumio sudo[5388]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 07 19:16:02 loovolumio sudo[5388]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:02 loovolumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 19:16:02 loovolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:02 loovolumio sudo[5388]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:02 loovolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 19:16:02 loovolumio sudo[5396]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:02 loovolumio volumio[4945]: info: Shairport-Sync Started Mar 07 19:16:03 loovolumio mpd[5401]: Mar 07 19:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 07 19:16:03 loovolumio systemd[1]: Started Music Player Daemon. Mar 07 19:16:03 loovolumio sudo[5379]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:03 loovolumio sudo[5356]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:03 loovolumio sudo[5308]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:03 loovolumio volumio[4945]: error: MPD error: The expression evaluated to a falsy value: Mar 07 19:16:03 loovolumio volumio[4945]: assert.ok(self.idling) Mar 07 19:16:03 loovolumio volumio[4945]: error: The expression evaluated to a falsy value: Mar 07 19:16:03 loovolumio volumio[4945]: assert.ok(self.idling) Mar 07 19:16:03 loovolumio volumio[4945]: error: updateQueue error: null Mar 07 19:16:03 loovolumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 07 19:16:03 loovolumio volumio[4945]: error: MPD error: The expression evaluated to a falsy value: Mar 07 19:16:03 loovolumio volumio[4945]: assert.ok(self.idling) Mar 07 19:16:03 loovolumio volumio[4945]: error: The expression evaluated to a falsy value: Mar 07 19:16:03 loovolumio volumio[4945]: assert.ok(self.idling) Mar 07 19:16:03 loovolumio volumio[4945]: error: MPD error: The expression evaluated to a falsy value: Mar 07 19:16:03 loovolumio volumio[4945]: assert.ok(self.idling) Mar 07 19:16:03 loovolumio volumio[4945]: error: The expression evaluated to a falsy value: Mar 07 19:16:03 loovolumio volumio[4945]: assert.ok(self.idling) Mar 07 19:16:03 loovolumio volumio[4945]: error: updateQueue error: null Mar 07 19:16:04 loovolumio volumio[5420]: cset: --> shielding system active with Mar 07 19:16:04 loovolumio volumio[5420]: cset: "system" cpuset of CPUSPEC(1-3) with 128 tasks running Mar 07 19:16:04 loovolumio volumio[5420]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 07 19:16:04 loovolumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 07 19:16:04 loovolumio systemd[1]: Reloading. Mar 07 19:16:04 loovolumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 07 19:16:05 loovolumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Mar 07 19:16:05 loovolumio sudo[5286]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:05 loovolumio volumio[4945]: info: succeeded Mar 07 19:16:07 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetState Mar 07 19:16:07 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:08 loovolumio volumio[4945]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 19:16:09 loovolumio sudo[5000]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 19:16:09 loovolumio volumio[4945]: info: Cannot mount NAS LooNAS at system boot, trial number 1 ,retrying in 5 seconds Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:09 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 07 19:16:09 loovolumio volumio[4945]: info: Adding plugin bluetooth to MyMusic Plugins Mar 07 19:16:09 loovolumio volumio[4945]: info: Adding plugin multiroom to MyMusic Plugins Mar 07 19:16:09 loovolumio volumio[4945]: info: Adding plugin metavolumio to MyMusic Plugins Mar 07 19:16:09 loovolumio volumio[4945]: info: Adding plugin cd_controller to MyMusic Plugins Mar 07 19:16:09 loovolumio volumio[4945]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 07 19:16:09 loovolumio volumio[4945]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 07 19:16:09 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 07 19:16:10 loovolumio volumio[4945]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 07 19:16:10 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 07 19:16:10 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:10 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:10 loovolumio volumio[4945]: info: Starting MyVolumio Remote Streaming Endpoints Mar 07 19:16:10 loovolumio volumio[4945]: info: MyVolumio login type: Token Mar 07 19:16:10 loovolumio volumio[4945]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 07 19:16:10 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 07 19:16:11 loovolumio volumio[4945]: info: Starting Streaming Service Transparent Proxy Mar 07 19:16:11 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 07 19:16:11 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 07 19:16:11 loovolumio volumio[4945]: info: Streaming services startup Mar 07 19:16:11 loovolumio volumio[4945]: info: Starting Streaming Daemon Mar 07 19:16:11 loovolumio sudo[5466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 07 19:16:11 loovolumio sudo[5466]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:12 loovolumio volumio[4945]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 07 19:16:12 loovolumio sudo[5466]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:12 loovolumio volumio[4945]: error: Cannot start Volumio Streaming Daemon Mar 07 19:16:12 loovolumio volumio[4945]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 07 19:16:12 loovolumio volumio[4945]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 07 19:16:12 loovolumio volumio[4945]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 07 19:16:13 loovolumio volumio[4945]: STREAMING PROXY: Starting server on port 3245 Mar 07 19:16:13 loovolumio volumio[4945]: Node JS runtime: 14 Mar 07 19:16:13 loovolumio volumio[4945]: info: MyVolumio token set successfully Mar 07 19:16:13 loovolumio volumio[4945]: info: MYVOLUMIO: Adding device Mar 07 19:16:13 loovolumio volumio[4945]: info: MYVOLUMIO: Evaluating Server Mar 07 19:16:13 loovolumio volumio[4945]: info: MyVolumio status changed Mar 07 19:16:13 loovolumio volumio[4945]: info: Streaming services startup Mar 07 19:16:13 loovolumio volumio[4945]: info: Starting Streaming Daemon Mar 07 19:16:13 loovolumio volumio[4945]: info: Removing browser output: myVolumio user plan is not superstar Mar 07 19:16:13 loovolumio volumio[4945]: info: Removing audio output: Mar 07 19:16:13 loovolumio volumio[4945]: info: Stoppping Tunnel 1 Mar 07 19:16:13 loovolumio sudo[5505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 07 19:16:13 loovolumio sudo[5505]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:13 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:13 loovolumio sudo[5508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 07 19:16:13 loovolumio sudo[5508]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:13 loovolumio sudo[5505]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:13 loovolumio volumio[4945]: error: Cannot start Volumio Streaming Daemon Mar 07 19:16:13 loovolumio volumio[4945]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 07 19:16:13 loovolumio volumio[4945]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 07 19:16:13 loovolumio sudo[5508]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:13 loovolumio volumio[4945]: info: Remote SSH Stopped Mar 07 19:16:14 loovolumio volumio[4945]: info: Setting Geolocation for MyVolumio to eu6 Mar 07 19:16:14 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:14 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:14 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:14 loovolumio sudo[5511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.132:/LooFritz\\FRITZ.NAS /mnt/NAS/LooNAS Mar 07 19:16:14 loovolumio sudo[5511]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:15 loovolumio volumio[4945]: info: Updating MyVolumio device info Mar 07 19:16:15 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:15 loovolumio volumio[4945]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 07 19:16:15 loovolumio volumio[4945]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 07 19:16:17 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:19 loovolumio volumio[4945]: info: MYVOLUMIO: Adding device Mar 07 19:16:19 loovolumio volumio[4945]: info: MYVOLUMIO: Evaluating Server Mar 07 19:16:20 loovolumio volumio[4945]: info: Setting Geolocation for MyVolumio to eu7 Mar 07 19:16:20 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:20 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:20 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:20 loovolumio volumio[4945]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 07 19:16:21 loovolumio volumio[4945]: info: Updating MyVolumio device info Mar 07 19:16:21 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:21 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:21 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:21 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:21 loovolumio volumio[4945]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 07 19:16:22 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 07 19:16:22 loovolumio volumio[4945]: info: Share LooNAS successfully unmounted Mar 07 19:16:22 loovolumio sudo[5537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.132:\\LooFritz\\FRITZ.NAS /mnt/NAS/LooNAS Mar 07 19:16:22 loovolumio sudo[5537]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:25 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:25 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 19:16:25 loovolumio volumio[4945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 07 19:16:25 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetState Mar 07 19:16:25 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:29 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 19:16:29 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:29 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 07 19:16:29 loovolumio volumio-remote-updater[647]: No test mode Mar 07 19:16:29 loovolumio volumio-remote-updater[647]: No alpha test mode Mar 07 19:16:29 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 07 19:16:29 loovolumio volumio[4945]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 07 19:16:29 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 07 19:16:30 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:30 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:30 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 07 19:16:30 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 07 19:16:30 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 07 19:16:30 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 07 19:16:30 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 19:16:30 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 19:16:32 loovolumio volumio[4945]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 07 19:16:32 loovolumio volumio[4945]: info: Completed starting MyVolumio Plugin Mar 07 19:16:32 loovolumio volumio[4945]: [Metrics] CommandRouter: 70s 492.46ms Mar 07 19:16:32 loovolumio volumio[4945]: info: CoreCommandRouter::volumiosetStartupVolume Mar 07 19:16:32 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 19:16:32 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 19:16:32 loovolumio volumio[4945]: info: CoreCommandRouter::Close All Modals sent Mar 07 19:16:32 loovolumio volumio[4945]: info: CoreCommandRouter::Close All Modals sent Mar 07 19:16:32 loovolumio volumio[4945]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 07 19:16:32 loovolumio volumio[4945]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 07 19:16:32 loovolumio volumio[4945]: info: camilladsp stopping service pid 5328... Mar 07 19:16:32 loovolumio volumio[4945]: info: camilladsp service terminated, instance 1 Mar 07 19:16:32 loovolumio volumio[4945]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 07 19:16:32 loovolumio volumio[4945]: info: camilladsp service started and running in background, instance 1 Mar 07 19:16:32 loovolumio volumio[4945]: info: camilladsp spawned new process with pid 5569, instance 1, run: true Mar 07 19:16:32 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:33 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 07 19:16:33 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 19:16:33 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 07 19:16:36 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:37 loovolumio volumio[4945]: info: camilladsp spawned new process with pid 5591, instance 1, run: true Mar 07 19:16:39 loovolumio volumio[4945]: info: BOOT COMPLETED Mar 07 19:16:40 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:45 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:50 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:53 loovolumio volumio[4945]: info: CoreCommandRouter::volumioPlay Mar 07 19:16:53 loovolumio volumio[4945]: info: CoreStateMachine::play index undefined Mar 07 19:16:53 loovolumio volumio[4945]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 07 19:16:53 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:53 loovolumio volumio[4945]: info: CoreStateMachine::startPlaybackTimer Mar 07 19:16:53 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:53 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand stop Mar 07 19:16:53 loovolumio volumio[4945]: info: sendMpdCommand stop took 2 milliseconds Mar 07 19:16:53 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand clear Mar 07 19:16:53 loovolumio volumio[4945]: info: Mar 07 19:16:53 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:53 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:53 loovolumio volumio[4945]: info: sendMpdCommand clear took 3 milliseconds Mar 07 19:16:53 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 07 19:16:53 loovolumio volumio[4945]: info: Mar 07 19:16:53 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:53 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:53 loovolumio volumio[4945]: info: Mar 07 19:16:53 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:53 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:53 loovolumio volumio[4945]: info: Mar 07 19:16:53 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:53 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:53 loovolumio volumio[4945]: info: Mar 07 19:16:53 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:53 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:53 loovolumio volumio[4945]: error: updateQueue error: null Mar 07 19:16:53 loovolumio volumio[4945]: info: Mar 07 19:16:53 loovolumio volumio[4945]: ---------------------------- MPD announces state update: options Mar 07 19:16:53 loovolumio volumio[4945]: error: updateQueue error: null Mar 07 19:16:53 loovolumio volumio[4945]: error: updateQueue error: null Mar 07 19:16:53 loovolumio volumio[4945]: error: updateQueue error: null Mar 07 19:16:53 loovolumio volumio[4945]: error: updateQueue error: null Mar 07 19:16:53 loovolumio volumio[4945]: info: ------------------------------ 12ms Mar 07 19:16:53 loovolumio volumio[4945]: info: sendMpdCommand consume 1 took 11 milliseconds Mar 07 19:16:53 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:53 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:53 loovolumio volumio[4945]: info: ------------------------------ 11ms Mar 07 19:16:53 loovolumio volumio[4945]: info: ------------------------------ 10ms Mar 07 19:16:53 loovolumio volumio[4945]: info: ------------------------------ 9ms Mar 07 19:16:53 loovolumio volumio[4945]: info: ------------------------------ 8ms Mar 07 19:16:53 loovolumio volumio[4945]: info: [1741371413995] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Mar 07 19:16:53 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Mar 07 19:16:53 loovolumio volumio[4945]: info: Mar 07 19:16:53 loovolumio volumio[4945]: ---------------------------- MPD announces state update: options Mar 07 19:16:53 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:53 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:53 loovolumio volumio[4945]: info: Mar 07 19:16:53 loovolumio volumio[4945]: ---------------------------- MPD announces state update: options Mar 07 19:16:53 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:53 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:54 loovolumio volumio[4945]: info: Mar 07 19:16:54 loovolumio volumio[4945]: ---------------------------- MPD announces state update: options Mar 07 19:16:54 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:54 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:54 loovolumio volumio[4945]: info: Mar 07 19:16:54 loovolumio volumio[4945]: ---------------------------- MPD announces state update: options Mar 07 19:16:54 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:54 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:54 loovolumio volumio[4945]: info: Mar 07 19:16:54 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:54 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:54 loovolumio volumio[4945]: info: sendMpdCommand status took 13 milliseconds Mar 07 19:16:54 loovolumio volumio[4945]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 11 milliseconds Mar 07 19:16:54 loovolumio volumio[4945]: info: sendMpdCommand status took 10 milliseconds Mar 07 19:16:54 loovolumio volumio[4945]: info: sendMpdCommand status took 8 milliseconds Mar 07 19:16:54 loovolumio volumio[4945]: info: sendMpdCommand status took 7 milliseconds Mar 07 19:16:54 loovolumio volumio[4945]: info: sendMpdCommand status took 5 milliseconds Mar 07 19:16:54 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:54 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand play Mar 07 19:16:54 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:54 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:54 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:54 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:54 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreStateMachine::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::volumioPushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:54 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreStateMachine::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::volumioPushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:54 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreStateMachine::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::volumioPushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:54 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreStateMachine::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::volumioPushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:54 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreStateMachine::pushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::volumioPushState Mar 07 19:16:54 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:54 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 72ms Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 102ms Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 101ms Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 100ms Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 98ms Mar 07 19:16:54 loovolumio volumio[4945]: info: Mar 07 19:16:54 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:54 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:54 loovolumio volumio[4945]: info: Mar 07 19:16:54 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:54 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:54 loovolumio volumio[4945]: info: Mar 07 19:16:54 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:54 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:54 loovolumio volumio[4945]: info: Mar 07 19:16:54 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:54 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 110ms Mar 07 19:16:54 loovolumio volumio[4945]: info: sendMpdCommand play took 95 milliseconds Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 13ms Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 12ms Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 10ms Mar 07 19:16:54 loovolumio volumio[4945]: info: ------------------------------ 10ms Mar 07 19:16:54 loovolumio volumio[4945]: info: [1741371414117] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 07 19:16:54 loovolumio sudo[5682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 07 19:16:54 loovolumio sudo[5682]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 07 19:16:54 loovolumio sudo[5685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 07 19:16:54 loovolumio sudo[5685]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 07 19:16:54 loovolumio sudo[5682]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio sudo[5688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 07 19:16:54 loovolumio sudo[5688]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppySpectrum ---peppyspectrum status stop Mar 07 19:16:54 loovolumio sudo[5691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 07 19:16:54 loovolumio sudo[5691]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppySpectrum ---peppyspectrum status stop Mar 07 19:16:54 loovolumio sudo[5688]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio sudo[5685]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio sudo[5693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 07 19:16:54 loovolumio sudo[5693]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppySpectrum ---peppyspectrum status stop Mar 07 19:16:54 loovolumio sudo[5698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 07 19:16:54 loovolumio sudo[5698]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio sudo[5697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 07 19:16:54 loovolumio sudo[5697]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppySpectrum ---peppyspectrum status stop Mar 07 19:16:54 loovolumio sudo[5691]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio volumio[4945]: info: PeppySpectrum ---peppyspectrum status stop Mar 07 19:16:54 loovolumio sudo[5703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 07 19:16:54 loovolumio sudo[5703]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio sudo[5706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 07 19:16:54 loovolumio sudo[5706]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio sudo[5693]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio sudo[5697]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio sudo[5698]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:54 loovolumio sudo[5709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 07 19:16:54 loovolumio sudo[5709]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:16:54 loovolumio volumio[4945]: info: peppyspectrum Daemon Stop Mar 07 19:16:54 loovolumio volumio[4945]: info: peppyspectrum Daemon Stop Mar 07 19:16:54 loovolumio volumio[4945]: info: peppymeterbasic Daemon Stop Mar 07 19:16:54 loovolumio volumio[4945]: info: peppymeterbasic Daemon Stop Mar 07 19:16:54 loovolumio volumio[4945]: info: peppymeterbasic Daemon Stop Mar 07 19:16:54 loovolumio volumio[4945]: info: peppymeterbasic Daemon Stop Mar 07 19:16:54 loovolumio sudo[5706]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio volumio[4945]: info: peppyspectrum Daemon Stop Mar 07 19:16:54 loovolumio sudo[5703]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio volumio[4945]: info: peppyspectrum Daemon Stop Mar 07 19:16:54 loovolumio sudo[5709]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:54 loovolumio volumio[4945]: info: peppyspectrum Daemon Stop Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 07 19:16:55 loovolumio volumio[4945]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 07 19:16:55 loovolumio volumio[4945]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 32 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 30 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 28 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 27 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 25 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 16 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 14 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 12 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 11 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 9 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 78ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 74ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 73ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 71ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 70ms Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:55 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 61ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 52ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 50ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 48ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 47ms Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:55 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:55 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:55 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces system playlist update Mar 07 19:16:55 loovolumio volumio[4945]: info: Ignoring MPD Status Update Mar 07 19:16:55 loovolumio volumio[4945]: info: Mar 07 19:16:55 loovolumio volumio[4945]: ---------------------------- MPD announces state update: player Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::getState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand status Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 29ms Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 28 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 15ms Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 14 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 13ms Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 13 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 13ms Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 11 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 17ms Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand status took 15 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseState Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: verbose: ControllerMpd::parseTrackInfo Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ControllerMpd::pushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:55 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:55 loovolumio volumio[4945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 57ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 42ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 41ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 38ms Mar 07 19:16:55 loovolumio volumio[4945]: info: ------------------------------ 35ms Mar 07 19:16:57 loovolumio volumio[4945]: info: [1741371417736] [RadioParadise] received new metadata: {"time":5,"artist":"","title":null,"album":null,"year":null,"cover":"https://img.radioparadise.com/covers/l/.jpg","cover_med":"https://img.radioparadise.com/covers/m/.jpg","cover_small":"https://img.radioparadise.com/covers/s/.jpg"} Mar 07 19:16:57 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:57 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:16:57 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:57 loovolumio volumio[4945]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":null,"title":null,"artist":"","album":null,"streaming":true,"disableUiControls":true,"duration":10,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 07 19:16:57 loovolumio volumio[4945]: verbose: CURRENT POSITION 0 Mar 07 19:16:57 loovolumio volumio[4945]: info: CoreStateMachine::syncState stateService play Mar 07 19:16:57 loovolumio volumio[4945]: info: CoreStateMachine::syncState currentStatus stop Mar 07 19:16:57 loovolumio volumio[4945]: info: [1741371417741] [RadioParadise] setting new timer with duration of 10000 seconds. Mar 07 19:16:57 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:16:57 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 1 Mar 07 19:16:58 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 19:16:59 loovolumio sudo[5511]: pam_unix(sudo:session): session closed for user root Mar 07 19:16:59 loovolumio volumio[4945]: info: Cannot mount NAS LooNAS at system boot, trial number 2 ,retrying in 5 seconds Mar 07 19:17:00 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetState Mar 07 19:17:00 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:17:04 loovolumio sudo[5737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.132:\\LooFritz\\FRITZ.NAS /mnt/NAS/LooNAS Mar 07 19:17:04 loovolumio sudo[5737]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:17:05 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetState Mar 07 19:17:05 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:17:07 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:07 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:07 loovolumio sudo[5537]: pam_unix(sudo:session): session closed for user root Mar 07 19:17:07 loovolumio volumio[4945]: info: An error occurred mounting the new share. Rolling back configuration Mar 07 19:17:07 loovolumio volumio[4945]: info: [1741371427744] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Mar 07 19:17:08 loovolumio volumio[4945]: info: [1741371428351] [RadioParadise] received new metadata: {"time":184,"artist":"Fleetwood Mac","title":"Landslide","album":"Fleetwood Mac","year":"1975","cover":"https://img.radioparadise.com/covers/l/B00009RAJH.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00009RAJH.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00009RAJH.jpg"} Mar 07 19:17:08 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreCommandRouter::servicePushState Mar 07 19:17:08 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:17:08 loovolumio volumio[4945]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00009RAJH.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Landslide","title":"Landslide","artist":"Fleetwood Mac","album":"Fleetwood Mac","streaming":true,"disableUiControls":true,"duration":189,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 07 19:17:08 loovolumio volumio[4945]: verbose: CURRENT POSITION 0 Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreStateMachine::syncState stateService play Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreStateMachine::syncState currentStatus play Mar 07 19:17:08 loovolumio volumio[4945]: info: Received an update from plugin. extracting info from payload Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreStateMachine::pushState Mar 07 19:17:08 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreCommandRouter::volumioPushState Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreStateMachine::pushState Mar 07 19:17:08 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreCommandRouter::volumioPushState Mar 07 19:17:08 loovolumio volumio[4945]: info: [1741371428384] [RadioParadise] setting new timer with duration of 189000 seconds. Mar 07 19:17:08 loovolumio volumio[4945]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 07 19:17:08 loovolumio volumio[4945]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 07 19:17:08 loovolumio sudo[5745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 07 19:17:08 loovolumio sudo[5745]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:17:08 loovolumio volumio[4945]: info: PeppySpectrum ---peppyspectrum status play Mar 07 19:17:08 loovolumio volumio[4945]: info: PeppySpectrum ---peppyspectrum status play Mar 07 19:17:08 loovolumio sudo[5748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 07 19:17:08 loovolumio sudo[5748]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:17:08 loovolumio sudo[5750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 07 19:17:08 loovolumio sudo[5750]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetQueue Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreStateMachine::getQueue Mar 07 19:17:08 loovolumio volumio[4945]: info: CorePlayQueue::getQueue Mar 07 19:17:08 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 07 19:17:08 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:17:08 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 1 Mar 07 19:17:08 loovolumio sudo[5754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Mar 07 19:17:08 loovolumio systemd[1]: Started peppymeterbasic Daemon. Mar 07 19:17:08 loovolumio sudo[5754]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 19:17:08 loovolumio sudo[5745]: pam_unix(sudo:session): session closed for user root Mar 07 19:17:08 loovolumio volumio[4945]: info: peppymeterbasic Daemon Started Mar 07 19:17:08 loovolumio systemd[1]: Started peppyspectrum Daemon. Mar 07 19:17:08 loovolumio sudo[5748]: pam_unix(sudo:session): session closed for user root Mar 07 19:17:08 loovolumio sudo[5754]: pam_unix(sudo:session): session closed for user root Mar 07 19:17:08 loovolumio volumio[4945]: info: peppymeterbasic Daemon Started Mar 07 19:17:08 loovolumio sudo[5750]: pam_unix(sudo:session): session closed for user root Mar 07 19:17:08 loovolumio volumio[4945]: info: peppyspectrum Daemon Started Mar 07 19:17:08 loovolumio volumio[4945]: info: peppyspectrum Daemon Started Mar 07 19:17:09 loovolumio volumio[5761]: pygame 1.9.4.post1 Mar 07 19:17:09 loovolumio volumio[5761]: Hello from the pygame community. https://www.pygame.org/contribute.html Mar 07 19:17:09 loovolumio volumio[5761]: Traceback (most recent call last): Mar 07 19:17:09 loovolumio volumio[5761]: File "spectrum.py", line 715, in Mar 07 19:17:09 loovolumio volumio[5761]: pm = Spectrum(None, True) Mar 07 19:17:09 loovolumio volumio[5761]: File "spectrum.py", line 63, in __init__ Mar 07 19:17:09 loovolumio volumio[5761]: self.init_display() Mar 07 19:17:09 loovolumio volumio[5761]: File "spectrum.py", line 104, in init_display Mar 07 19:17:09 loovolumio volumio[5761]: pygame.display.init() Mar 07 19:17:09 loovolumio volumio[5761]: pygame.error: Unable to open /dev/fb1 Mar 07 19:17:09 loovolumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Mar 07 19:17:09 loovolumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Mar 07 19:17:10 loovolumio volumio[5752]: pygame 1.9.4.post1 Mar 07 19:17:10 loovolumio volumio[5752]: Hello from the pygame community. https://www.pygame.org/contribute.html Mar 07 19:17:13 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:13 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:14 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:15 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:17 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:17 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$0 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$1 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$2 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$3 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$4 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$5 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$6 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$7 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$8 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$9 Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$A Mar 07 19:17:17 loovolumio volumio[4945]: info: Preloading song: upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$B Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$0 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$1 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$2 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$3 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$4 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$5 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$6 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$7 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$8 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$9 in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$A in service upnp_browser Mar 07 19:17:17 loovolumio volumio[4945]: info: Exploding uri upnp/http://192.168.1.108:8200/ctl/ContentDir@1$4$B in service upnp_browser Mar 07 19:17:23 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 07 19:17:23 loovolumio volumio[4945]: info: Received Get System Info Mar 07 19:17:23 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 19:17:23 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 19:17:23 loovolumio volumio[4945]: info: Discovery: Getting this device information Mar 07 19:17:23 loovolumio volumio[4945]: info: CoreCommandRouter::volumioGetState Mar 07 19:17:23 loovolumio volumio[4945]: info: CorePlayQueue::getTrack 0 Mar 07 19:17:23 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 19:17:25 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:25 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:30 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:30 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:33 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:33 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:34 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:34 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:35 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:35 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:36 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:36 loovolumio volumio[4945]: info: Preload queue cleared Mar 07 19:17:37 loovolumio volumio[4945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 19:17:37 loovolumio volumio[4945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 19:17:37 loovolumio volumio[4945]: TypeError: Cannot read property 'length' of undefined Mar 07 19:17:37 loovolumio volumio[4945]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 07 19:17:37 loovolumio volumio[4945]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 07 19:17:37 loovolumio volumio[4945]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 07 19:17:37 loovolumio volumio[4945]: at Parser.emit (events.js:315:20) Mar 07 19:17:37 loovolumio volumio[4945]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 07 19:17:37 loovolumio volumio[4945]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 07 19:17:37 loovolumio volumio[4945]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 07 19:17:37 loovolumio volumio[4945]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 07 19:17:37 loovolumio volumio[4945]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 07 19:17:37 loovolumio volumio[4945]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 07 19:17:37 loovolumio volumio[4945]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 07 19:17:37 loovolumio volumio[4945]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 07 19:17:37 loovolumio volumio[4945]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 07 19:17:37 loovolumio volumio[4945]: at IncomingMessage.emit (events.js:327:22) Mar 07 19:17:37 loovolumio volumio[4945]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 07 19:17:37 loovolumio volumio[4945]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 07 19:17:37 loovolumio volumio[4945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 19:17:38 loovolumio sudo[5831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-07 19:16 Mar 07 19:17:38 loovolumio sudo[5831]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"