-- Logs begin at Tue 2024-06-25 11:49:58 CEST, end at Sat 2024-07-13 16:19:51 CEST. -- Jul 13 16:18:02 r-i-v-o ntpd[3241]: receive: Unexpected origin timestamp 0xea25115d.7670eb92 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xea3d0b1a.4b00ebfe Jul 13 16:18:02 r-i-v-o ntpd[3241]: receive: Unexpected origin timestamp 0xea25115d.765ab8a0 does not match aorg 0000000000.00000000 from server@80.211.133.9 xmt 0xea3d0b1a.4b501800 Jul 13 16:18:02 r-i-v-o ntpd[3241]: receive: Unexpected origin timestamp 0xea25115d.767325b4 does not match aorg 0000000000.00000000 from server@212.45.144.3 xmt 0xea3d0b1a.4ae56930 Jul 13 16:18:02 r-i-v-o ntpd[3241]: receive: Unexpected origin timestamp 0xea25115d.766973c3 does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xea3d0b1a.4c385d22 Jul 13 16:18:02 r-i-v-o ntpd[3241]: receive: Unexpected origin timestamp 0xea25115d.766e7934 does not match aorg 0000000000.00000000 from server@185.157.229.254 xmt 0xea3d0b1a.4cd94458 Jul 13 16:18:02 r-i-v-o ntpd[3241]: receive: Unexpected origin timestamp 0xea25115d.7664042e does not match aorg 0000000000.00000000 from server@185.19.184.35 xmt 0xea3d0b1a.4c991336 Jul 13 16:18:02 r-i-v-o systemd[1]: Starting Daily man-db regeneration... Jul 13 16:18:02 r-i-v-o systemd[1]: Starting Daily apt download activities... Jul 13 16:18:02 r-i-v-o ntpd[3241]: receive: Unexpected origin timestamp 0xea25115d.7666fdf0 does not match aorg 0000000000.00000000 from server@185.208.47.14 xmt 0xea3d0b1a.4d1fa0c8 Jul 13 16:18:02 r-i-v-o systemd[1]: man-db.service: Succeeded. Jul 13 16:18:02 r-i-v-o systemd[1]: Started Daily man-db regeneration. Jul 13 16:18:02 r-i-v-o volumio[3097]: info: Applying required configuration parameters for plugin cec_controller Jul 13 16:18:02 r-i-v-o volumio[3097]: info: Plugin mpdemulation is not enabled Jul 13 16:18:02 r-i-v-o volumio[3097]: info: Loading plugin "rest_api"... Jul 13 16:18:02 r-i-v-o volumio[3097]: info: Loading plugin "websocket"... Jul 13 16:18:02 r-i-v-o volumio[3097]: info: Starting Socket.io Server version 2.3.0 Jul 13 16:18:02 r-i-v-o volumio[3097]: info: Loading i18n strings for locale it Jul 13 16:18:02 r-i-v-o volumio[3097]: Updating browse sources language Jul 13 16:18:02 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::initPlayerControls Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:18:03 r-i-v-o volumio[3097]: Express server listening on port 3000 Jul 13 16:18:03 r-i-v-o volumio[3097]: [Metrics] WebUI: 16s 619.26ms Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreStateMachine::resetVolumioState Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreStateMachine::getcurrentVolume Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioRetrievevolume Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:03 r-i-v-o sudo[3404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 13 16:18:03 r-i-v-o sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:03 r-i-v-o systemd[1]: apt-daily.service: Succeeded. Jul 13 16:18:03 r-i-v-o systemd[1]: Started Daily apt download activities. Jul 13 16:18:03 r-i-v-o volumio[3097]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 13 16:18:03 r-i-v-o sudo[3404]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Completed loading Core Plugins Jul 13 16:18:03 r-i-v-o sudo[3407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Preparing to generate the ALSA configuration file Jul 13 16:18:03 r-i-v-o sudo[3407]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:03 r-i-v-o systemd[1]: Starting Daily apt upgrade and clean activities... Jul 13 16:18:03 r-i-v-o sudo[3407]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:03 r-i-v-o volumio[3097]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'iFi (by AMR) HD USB Audio ',0 Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 13 16:18:03 r-i-v-o volumio-remote-updater[2839]: [2024-07-13 16:18:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1719309020 101 Jul 13 16:18:03 r-i-v-o volumio[3097]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jul 13 16:18:03 r-i-v-o volumio[3097]: info: VolumeController:: Volume=undefined Mute =false Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreStateMachine::updateTrackBlock Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrackBlock Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioRetrievevolume Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Reloading queue from file Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Asound.conf file unchanged, so no further update is needed Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Output device has changed, restarting MPD Jul 13 16:18:03 r-i-v-o sudo[3445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 16:18:03 r-i-v-o sudo[3445]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:03 r-i-v-o volumio[3097]: info: ___________ START PLUGINS ___________ Jul 13 16:18:03 r-i-v-o sudo[3445]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:03 r-i-v-o volumio[3097]: info: ControllerMpd::onStart: Initializing MPD Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Creating MPD Configuration file Jul 13 16:18:03 r-i-v-o sudo[3449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 16:18:03 r-i-v-o sudo[3449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Adding Manifest REST API Endpoints Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 16:18:03 r-i-v-o volumio[3097]: info: [1720880283807] CoreMusicLibrary::Adding element Last_100 Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 16:18:03 r-i-v-o systemd[1]: Listening on mpd.socket. Jul 13 16:18:03 r-i-v-o sudo[3455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 16:18:03 r-i-v-o sudo[3455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Streaming services startup Jul 13 16:18:03 r-i-v-o systemd[1]: Starting Music Player Daemon... Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Starting Streaming Daemon Jul 13 16:18:03 r-i-v-o sudo[3455]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:03 r-i-v-o sudo[3457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 16:18:03 r-i-v-o sudo[3457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:03 r-i-v-o systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 13 16:18:03 r-i-v-o systemd[1]: mpd.service: Succeeded. Jul 13 16:18:03 r-i-v-o systemd[1]: Stopped Music Player Daemon. Jul 13 16:18:03 r-i-v-o sudo[3464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 13 16:18:03 r-i-v-o sudo[3464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:03 r-i-v-o systemd[1]: Starting Music Player Daemon... Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 16:18:03 r-i-v-o volumio[3097]: info: [1720880283921] CoreMusicLibrary::Adding element Webradio Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 16:18:03 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 16:18:03 r-i-v-o sudo[3464]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:03 r-i-v-o volumio[3097]: info: Initializing BBC Radios Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:04 r-i-v-o volumio[3097]: error: Hi Res Audio Failed Login: Missing Login Data Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Initializing Serial Communication on port /dev/ttyS3 Jul 13 16:18:04 r-i-v-o sudo[3469]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 13 16:18:04 r-i-v-o sudo[3469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:04 r-i-v-o kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jul 13 16:18:04 r-i-v-o kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jul 13 16:18:04 r-i-v-o kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Touch Event Listener Process Starting Jul 13 16:18:04 r-i-v-o sudo[3469]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 13 16:18:04 r-i-v-o sudo[3469]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Refreshing TIDAL token Jul 13 16:18:04 r-i-v-o sudo[3494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jul 13 16:18:04 r-i-v-o sudo[3494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:04 r-i-v-o sudo[3494]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:04 r-i-v-o sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jul 13 16:18:04 r-i-v-o sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:04 r-i-v-o sudo[3510]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:04 r-i-v-o systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 13 16:18:04 r-i-v-o systemd[1]: Started Daily apt upgrade and clean activities. Jul 13 16:18:04 r-i-v-o sudo[3515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jul 13 16:18:04 r-i-v-o sudo[3515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:04 r-i-v-o sudo[3515]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:04 r-i-v-o sudo[3519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jul 13 16:18:04 r-i-v-o sudo[3519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:04 r-i-v-o sudo[3519]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:04 r-i-v-o sudo[3522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jul 13 16:18:04 r-i-v-o sudo[3522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:04 r-i-v-o sudo[3522]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Apply VIM3L Onboard LEDs Settings Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Turning On Onboard LEDs Jul 13 16:18:04 r-i-v-o sudo[3526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 13 16:18:04 r-i-v-o sudo[3526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Starting CEC Event listener Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Volumio Calling Home Jul 13 16:18:04 r-i-v-o sudo[3526]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:04 r-i-v-o sudo[3531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 13 16:18:04 r-i-v-o sudo[3531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:04 r-i-v-o sudo[3531]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Stopping AccessToken refresher cron for QOBUZ Jul 13 16:18:04 r-i-v-o volumio[3097]: info: AccessToken refresher cron started for QOBUZ Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Adding QOBUZ REST API Endpoints Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreStateMachine::setRepeat false single undefined Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreStateMachine::setRandom null Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:18:04 r-i-v-o volumio[3097]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'iFi (by AMR) HD USB Audio ',0 Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Serial port opened successfully Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Sending serial start messages Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:04 r-i-v-o volumio[3097]: info: MP1 GPIO: Signalled system ready via GPIO Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Touch Event Listener Process Closed Jul 13 16:18:04 r-i-v-o volumio[3097]: error: Cannot start Volumio Streaming Daemon Jul 13 16:18:04 r-i-v-o volumio[3097]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 13 16:18:04 r-i-v-o volumio[3097]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 13 16:18:04 r-i-v-o volumio[3097]: info: MPD Permissions set Jul 13 16:18:04 r-i-v-o volumio[3097]: info: MPD Permissions set Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Setting Device Volume Override Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Updating Volume Controller Parameters: Device: 5 Name: iFi (by AMR) HD USB Audio Mixer: iFi (by AMR) HD USB Audio Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 13 16:18:04 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 13 16:18:04 r-i-v-o volumio[3097]: info: Disabling external Volume Control Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: VolumeController:: Volume=undefined Mute =false Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Volumio called home Jul 13 16:18:05 r-i-v-o sudo[3542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 13 16:18:05 r-i-v-o sudo[3542]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Setting Additional System Software info: Hardware Revision: 2.0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Setting HW Firmware info: undefined Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Setting HW Version info: 2.0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.3 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Setting HW Firmware info: 0.3.3 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Setting HW Version info: 2.0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 13 16:18:05 r-i-v-o volumio[3097]: info: MCU Signalled Headphone Mode Disabled Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: MCU Signalled Sleep Mode Disabled Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Enabling Advanced system settings configuration Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Additional UI Settings Added for plugin music_service/inputs Jul 13 16:18:05 r-i-v-o volumio[3097]: info: MCU Signalled Auto Boot Mode On Power Disabled Jul 13 16:18:05 r-i-v-o systemd[1]: Started UPnP Renderer front-end to MPD. Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Discovery: adding 2490110f-247b-45ac-a3dc-112604566dbc Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Discovery: Found device R I V O Jul 13 16:18:05 r-i-v-o sudo[3542]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o sudo[3546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jul 13 16:18:05 r-i-v-o sudo[3546]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:05 r-i-v-o sudo[3546]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:05 r-i-v-o volumio[3097]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 13 16:18:05 r-i-v-o volumio[3097]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'iFi (by AMR) HD USB Audio ',0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:05 r-i-v-o volumio[3097]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Discovery: A device disappeared from network Jul 13 16:18:05 r-i-v-o volumio[3097]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Upmpdcli Daemon Started Jul 13 16:18:05 r-i-v-o sudo[3565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jul 13 16:18:05 r-i-v-o sudo[3565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:05 r-i-v-o sudo[3565]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:05 r-i-v-o volumio[3097]: info: VolumeController:: Volume=undefined Mute =false Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Adding Inputs via Serial API Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Discovery: adding 2490110f-247b-45ac-a3dc-112604566dbc Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Discovery: Found device R I V O Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::servicePushState Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received inputs Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 13 16:18:05 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 16:18:05 r-i-v-o volumio[3097]: info: MCU Signalled Playback Inactive Jul 13 16:18:05 r-i-v-o volumio[3097]: info: Access Token successfully retrieved Jul 13 16:18:06 r-i-v-o volumio[3097]: info: Successfully retrieved User Session From TIDAL Jul 13 16:18:06 r-i-v-o volumio[3097]: info: Successfully retrieved User Subscription From TIDAL Jul 13 16:18:06 r-i-v-o volumio[3097]: info: Adding TIDAL to Browse Sources Jul 13 16:18:06 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 13 16:18:06 r-i-v-o volumio[3097]: info: [1720880286380] CoreMusicLibrary::Adding element TIDAL Jul 13 16:18:06 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 16:18:06 r-i-v-o volumio[3097]: Cannot find translation for source TIDAL Jul 13 16:18:06 r-i-v-o volumio[3097]: info: Stopping AccessToken refresher cron Jul 13 16:18:06 r-i-v-o volumio[3097]: info: AccessToken refresher cron started Jul 13 16:18:06 r-i-v-o volumio[3097]: info: Adding TIDAL REST API Endpoints Jul 13 16:18:06 r-i-v-o volumio[3097]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 13 16:18:06 r-i-v-o volumio[3097]: info: Turning Off Onboard LEDs Jul 13 16:18:06 r-i-v-o sudo[3571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 13 16:18:06 r-i-v-o sudo[3571]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:06 r-i-v-o sudo[3571]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:06 r-i-v-o mpd[3496]: Jul 13 16:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 16:18:06 r-i-v-o sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 13 16:18:06 r-i-v-o sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:06 r-i-v-o sudo[3574]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:06 r-i-v-o volumio[3097]: info: Onboard LEDs initialized Jul 13 16:18:06 r-i-v-o systemd[1]: Started Music Player Daemon. Jul 13 16:18:06 r-i-v-o sudo[3457]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:06 r-i-v-o sudo[3449]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:07 r-i-v-o volumio[3097]: info: Completed starting Core Plugins Jul 13 16:18:07 r-i-v-o volumio[3097]: info: ------------------------------------------- Jul 13 16:18:07 r-i-v-o volumio[3097]: info: ----- MyVolumio plugins startup ---- Jul 13 16:18:07 r-i-v-o volumio[3097]: info: ------------------------------------------- Jul 13 16:18:07 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 13 16:18:07 r-i-v-o volumio[3097]: error: MPD error: The expression evaluated to a falsy value: Jul 13 16:18:07 r-i-v-o volumio[3097]: assert.ok(self.idling) Jul 13 16:18:07 r-i-v-o volumio[3097]: error: The expression evaluated to a falsy value: Jul 13 16:18:07 r-i-v-o volumio[3097]: assert.ok(self.idling) Jul 13 16:18:07 r-i-v-o volumio[3097]: info: MPD running with PID3496 Jul 13 16:18:07 r-i-v-o volumio[3097]: ,establishing connection Jul 13 16:18:07 r-i-v-o volumio[3097]: error: updateQueue error: null Jul 13 16:18:07 r-i-v-o volumio[3097]: error: updateQueue error: null Jul 13 16:18:07 r-i-v-o volumio[3545]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 13 16:18:09 r-i-v-o volumio[3545]: .......................++++ Jul 13 16:18:11 r-i-v-o systemd[1]: systemd-fsckd.service: Succeeded. Jul 13 16:18:12 r-i-v-o volumio[3097]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 13 16:18:12 r-i-v-o volumio[3545]: ............................................++++ Jul 13 16:18:12 r-i-v-o volumio[3545]: e is 65537 (0x010001) Jul 13 16:18:12 r-i-v-o volumio[3545]: writing RSA key Jul 13 16:18:14 r-i-v-o volumio[3097]: info: Checking for updated MCU Firmware Jul 13 16:18:14 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 16:18:14 r-i-v-o volumio[3097]: info: Firware on device is on latest version, no need to update Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 13 16:18:15 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 13 16:18:16 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 13 16:18:16 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 13 16:18:16 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:16 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:16 r-i-v-o volumio[3097]: info: Starting MyVolumio Remote Streaming Endpoints Jul 13 16:18:16 r-i-v-o volumio[3097]: info: MyVolumio login type: Token Jul 13 16:18:16 r-i-v-o volumio[3097]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 13 16:18:16 r-i-v-o volumio[3097]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jul 13 16:18:17 r-i-v-o volumio[3097]: error: MyVolumio Custom Token format not valid, refreshing it Jul 13 16:18:18 r-i-v-o volumio[3097]: info: MyVolumio login type: Token Jul 13 16:18:18 r-i-v-o volumio[3097]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 13 16:18:19 r-i-v-o volumio[3097]: info: MyVolumio token set successfully Jul 13 16:18:19 r-i-v-o volumio[3097]: info: MYVOLUMIO: Adding device Jul 13 16:18:19 r-i-v-o volumio[3097]: info: MYVOLUMIO: Evaluating Server Jul 13 16:18:19 r-i-v-o volumio[3097]: info: MyVolumio status changed Jul 13 16:18:19 r-i-v-o volumio[3097]: info: Streaming services startup Jul 13 16:18:19 r-i-v-o volumio[3097]: info: Starting Streaming Daemon Jul 13 16:18:19 r-i-v-o volumio[3097]: info: Removing browser output: myVolumio user plan is not superstar Jul 13 16:18:19 r-i-v-o volumio[3097]: info: Removing audio output: Jul 13 16:18:19 r-i-v-o volumio[3097]: info: Stoppping Tunnel 1 Jul 13 16:18:19 r-i-v-o sudo[3627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 13 16:18:19 r-i-v-o sudo[3627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:19 r-i-v-o sudo[3627]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:19 r-i-v-o sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 13 16:18:19 r-i-v-o sudo[3630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:19 r-i-v-o volumio[3097]: error: Cannot start Volumio Streaming Daemon Jul 13 16:18:19 r-i-v-o volumio[3097]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 13 16:18:19 r-i-v-o volumio[3097]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 13 16:18:19 r-i-v-o sudo[3630]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:19 r-i-v-o volumio[3097]: info: Remote SSH Stopped Jul 13 16:18:20 r-i-v-o volumio[3097]: info: Setting Geolocation for MyVolumio to eu4 Jul 13 16:18:20 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:20 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:20 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:20 r-i-v-o volumio[3097]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 13 16:18:21 r-i-v-o volumio[3097]: info: Updating MyVolumio device info Jul 13 16:18:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:21 r-i-v-o volumio[3097]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 13 16:18:23 r-i-v-o systemd[1]: systemd-hostnamed.service: Succeeded. Jul 13 16:18:24 r-i-v-o volumio[3097]: info: MYVOLUMIO: Adding device Jul 13 16:18:24 r-i-v-o volumio[3097]: info: MYVOLUMIO: Evaluating Server Jul 13 16:18:25 r-i-v-o volumio[3097]: info: Setting Geolocation for MyVolumio to eu6 Jul 13 16:18:25 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:25 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:25 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:26 r-i-v-o volumio[3097]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 13 16:18:26 r-i-v-o volumio[3097]: info: Updating MyVolumio device info Jul 13 16:18:26 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:26 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:26 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:26 r-i-v-o volumio[3097]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 13 16:18:27 r-i-v-o volumio[3097]: error: MyVolumio Plugin failed to start in a timely fashion Jul 13 16:18:27 r-i-v-o volumio[3097]: info: BOOT COMPLETED Jul 13 16:18:27 r-i-v-o volumio[3097]: [Metrics] CommandRouter: 39s 488.45ms Jul 13 16:18:27 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumiosetStartupVolume Jul 13 16:18:27 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:27 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:27 r-i-v-o volumio[3097]: info: CoreCommandRouter::Close All Modals sent Jul 13 16:18:27 r-i-v-o volumio[3097]: info: CoreCommandRouter::Close All Modals sent Jul 13 16:18:27 r-i-v-o kernel: fb: mem_free_work, free memory: addr:800000 Jul 13 16:18:28 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 13 16:18:28 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 13 16:18:28 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 13 16:18:31 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 16:18:31 r-i-v-o volumio[3097]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 13 16:18:31 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:18:31 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:34 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 16:18:34 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:18:34 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 13 16:18:34 r-i-v-o volumio-remote-updater[2839]: No test mode Jul 13 16:18:34 r-i-v-o volumio-remote-updater[2839]: No alpha test mode Jul 13 16:18:34 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 13 16:18:34 r-i-v-o volumio[3097]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 13 16:18:34 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 13 16:18:36 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:36 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:18:36 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 13 16:18:36 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 13 16:18:36 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 13 16:18:36 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetBrowseSources Jul 13 16:18:36 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 16:18:42 r-i-v-o volumiologrotate[2838]: ls: cannot access '/var/log/samba/log.wb-R': No such file or directory Jul 13 16:18:42 r-i-v-o volumiologrotate[2838]: ls: cannot access 'I': No such file or directory Jul 13 16:18:42 r-i-v-o volumiologrotate[2838]: ls: cannot access 'V': No such file or directory Jul 13 16:18:42 r-i-v-o volumiologrotate[2838]: ls: cannot access 'O': No such file or directory Jul 13 16:18:55 r-i-v-o sudo[3708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 13 16:18:55 r-i-v-o sudo[3708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:55 r-i-v-o sudo[3708]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:55 r-i-v-o sudo[3710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 13 16:18:55 r-i-v-o sudo[3710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:18:55 r-i-v-o sudo[3710]: pam_unix(sudo:session): session closed for user root Jul 13 16:18:55 r-i-v-o volumio[3097]: verbose: New Socket.io Connection to 192.168.1.217 from 192.168.1.175 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:128.0) Gecko/20100101 Firefox/128.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 13 16:18:55 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:18:55 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:56 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 13 16:18:56 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetVisibleSources Jul 13 16:18:56 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 13 16:18:56 r-i-v-o volumio[3097]: info: Listing playlists Jul 13 16:18:56 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 13 16:18:56 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 13 16:18:56 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 13 16:18:56 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 16:18:58 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 16:18:58 r-i-v-o volumio[3097]: info: Received Get System Info Jul 13 16:18:58 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 16:18:58 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 16:18:58 r-i-v-o volumio[3097]: info: Discovery: Getting this device information Jul 13 16:18:58 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:18:58 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:58 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 16:18:59 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 13 16:18:59 r-i-v-o volumio[3097]: info: Received Get System Info Jul 13 16:18:59 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 16:18:59 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 16:18:59 r-i-v-o volumio[3097]: info: Discovery: Getting this device information Jul 13 16:18:59 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:18:59 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:18:59 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 16:19:01 r-i-v-o volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 16:19:01 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 13 16:19:01 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:19:01 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 13 16:19:01 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:01 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 13 16:19:01 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:19:09 r-i-v-o volumio[3097]: info: Restoring Previous Volume level: 100 false false Jul 13 16:19:09 r-i-v-o volumio[3097]: info: Output device has changed, restarting MPD Jul 13 16:19:09 r-i-v-o sudo[3720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 16:19:09 r-i-v-o sudo[3720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:09 r-i-v-o volumio[3097]: info: Volume configurations have been set Jul 13 16:19:09 r-i-v-o sudo[3720]: pam_unix(sudo:session): session closed for user root Jul 13 16:19:09 r-i-v-o sudo[3723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 16:19:09 r-i-v-o sudo[3723]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:19:09 r-i-v-o systemd[1]: Stopping Music Player Daemon... Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 13 16:19:09 r-i-v-o volumio[3097]: info: Updating Volume Controller Parameters: Device: 5 Name: iFi (by AMR) HD USB Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 13 16:19:09 r-i-v-o volumio[3097]: info: Disabling external Volume Control Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:19:09 r-i-v-o volumio[3097]: info: MPD Permissions set Jul 13 16:19:09 r-i-v-o volumio[3097]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'iFi (by AMR) HD USB Audio ',0 Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:09 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 16:19:10 r-i-v-o systemd[1]: mpd.service: Succeeded. Jul 13 16:19:10 r-i-v-o systemd[1]: Stopped Music Player Daemon. Jul 13 16:19:10 r-i-v-o systemd[1]: Starting Music Player Daemon... Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 13 16:19:10 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 13 16:19:10 r-i-v-o sudo[3734]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 13 16:19:10 r-i-v-o sudo[3734]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:19:10 r-i-v-o sudo[3734]: pam_unix(sudo:session): session closed for user root Jul 13 16:19:11 r-i-v-o mpd[3746]: Jul 13 16:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 16:19:11 r-i-v-o systemd[1]: Started Music Player Daemon. Jul 13 16:19:11 r-i-v-o sudo[3723]: pam_unix(sudo:session): session closed for user root Jul 13 16:19:11 r-i-v-o volumio[3097]: error: updateQueue error: null Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 13 16:19:13 r-i-v-o volumio[3097]: info: Received Get System Version Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 13 16:19:13 r-i-v-o volumio[3097]: info: Received Get System Info Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 13 16:19:13 r-i-v-o volumio[3097]: info: Discovery: Getting this device information Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetState Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:19:13 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 13 16:19:14 r-i-v-o volumio[3097]: info: VolumeController::SetAlsaVolume100 Jul 13 16:19:14 r-i-v-o volumio[3097]: info: CoreStateMachine::pushState Jul 13 16:19:14 r-i-v-o volumio[3097]: info: CorePlayQueue::getTrack 0 Jul 13 16:19:14 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioPushState Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Retrieving Cloud Streaming UI Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Getting Tidal Cloud Configuration Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Getting Qobuz Cloud Configuration Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Asking plugin for UI Config Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Getting Spotify Cloud Configuration Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Asking plugin for UI Config Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Saving Spotify Acccount Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Got it Jul 13 16:19:21 r-i-v-o volumio[3097]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Got Tidal Cloud Configuration Jul 13 16:19:21 r-i-v-o volumio[3097]: info: Got it Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetBrowseSources Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetBrowseSources Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::volumioGetBrowseSources Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 13 16:19:21 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 13 16:19:24 r-i-v-o volumio[3097]: info: Disabling MyMusic plugin upnp Jul 13 16:19:24 r-i-v-o sudo[3750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 13 16:19:24 r-i-v-o sudo[3750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 16:19:24 r-i-v-o systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 13 16:19:24 r-i-v-o volumio[3097]: error: Upnp client error: Error: This socket has been ended by the other party Jul 13 16:19:25 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 13 16:19:29 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 13 16:19:33 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 13 16:19:37 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 13 16:19:41 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 13 16:19:42 r-i-v-o volumiologrotate[2838]: ls: cannot access '/var/log/samba/log.wb-R': No such file or directory Jul 13 16:19:42 r-i-v-o volumiologrotate[2838]: ls: cannot access 'I': No such file or directory Jul 13 16:19:42 r-i-v-o volumiologrotate[2838]: ls: cannot access 'V': No such file or directory Jul 13 16:19:42 r-i-v-o volumiologrotate[2838]: ls: cannot access 'O': No such file or directory Jul 13 16:19:44 r-i-v-o volumio[3097]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jul 13 16:19:44 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jul 13 16:19:44 r-i-v-o volumio[3097]: info: Saving configuration Jul 13 16:19:44 r-i-v-o volumio[3097]: info: Done Jul 13 16:19:45 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 13 16:19:46 r-i-v-o volumio[3097]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jul 13 16:19:46 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jul 13 16:19:46 r-i-v-o volumio[3097]: info: Saving configuration Jul 13 16:19:46 r-i-v-o volumio[3097]: info: Done Jul 13 16:19:49 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 13 16:19:50 r-i-v-o volumio[3097]: info: Enabling MyMusic plugin upnp Jul 13 16:19:50 r-i-v-o volumio[3097]: info: Enabling plugin upnp Jul 13 16:19:50 r-i-v-o volumio[3097]: info: Loading plugin "upnp"... Jul 13 16:19:50 r-i-v-o volumio[3097]: info: [1720880390344] Starting Upmpd Daemon Jul 13 16:19:50 r-i-v-o volumio[3097]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 13 16:19:50 r-i-v-o volumio[3097]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 16:19:50 r-i-v-o volumio[3097]: Error: listen EADDRINUSE: address already in use :::6599 Jul 13 16:19:50 r-i-v-o volumio[3097]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jul 13 16:19:50 r-i-v-o volumio[3097]: at listenInCluster (net.js:1379:12) Jul 13 16:19:50 r-i-v-o volumio[3097]: at Server.listen (net.js:1465:7) Jul 13 16:19:50 r-i-v-o volumio[3097]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 13 16:19:50 r-i-v-o volumio[3097]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 13 16:19:50 r-i-v-o volumio[3097]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 13 16:19:50 r-i-v-o volumio[3097]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 13 16:19:50 r-i-v-o volumio[3097]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jul 13 16:19:50 r-i-v-o volumio[3097]: code: 'EADDRINUSE', Jul 13 16:19:50 r-i-v-o volumio[3097]: errno: -98, Jul 13 16:19:50 r-i-v-o volumio[3097]: syscall: 'listen', Jul 13 16:19:50 r-i-v-o volumio[3097]: address: '::', Jul 13 16:19:50 r-i-v-o volumio[3097]: port: 6599 Jul 13 16:19:50 r-i-v-o volumio[3097]: } Jul 13 16:19:50 r-i-v-o volumio[3097]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 16:19:51 r-i-v-o sudo[3808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 16:18 Jul 13 16:19:51 r-i-v-o sudo[3808]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:27:25 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="891b8adfc94c7cc44424972b60e8e09c"