-- Logs begin at Tue 2025-07-08 17:29:24 CEST, end at Tue 2025-07-08 17:31:10 CEST. -- Jul 08 17:30:00 rivo volumio[3289]: info: Reloading queue from file Jul 08 17:30:00 rivo volumio[3289]: info: CoreStateMachine::setRepeat null single undefined Jul 08 17:30:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:30:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:30:00 rivo volumio[3289]: info: CoreStateMachine::setRandom null Jul 08 17:30:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:30:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:30:00 rivo volumio[3289]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jul 08 17:30:00 rivo volumio[3289]: info: Asound.conf file unchanged, so no further update is needed Jul 08 17:30:00 rivo volumio[3289]: info: Output device has changed, restarting MPD Jul 08 17:30:00 rivo sudo[3928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 08 17:30:00 rivo sudo[3928]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:00 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Jul 08 17:30:00 rivo sudo[3928]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:00 rivo volumio[3289]: info: Output device has changed, restarting Shairport Sync Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:00 rivo sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 17:30:00 rivo sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:00 rivo sudo[3934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 17:30:00 rivo sudo[3934]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:00 rivo sudo[3931]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:00 rivo systemd[1]: Stopping Music Player Daemon... Jul 08 17:30:00 rivo volumio[3289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 17:30:00 rivo volumio[3289]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:00 rivo volumio[3289]: info: ___________ START PLUGINS ___________ Jul 08 17:30:00 rivo volumio[3289]: info: ControllerMpd::onStart: Initializing MPD Jul 08 17:30:00 rivo volumio[3289]: info: Creating MPD Configuration file Jul 08 17:30:00 rivo systemd[1]: mpd.service: Succeeded. Jul 08 17:30:00 rivo systemd[1]: Stopped Music Player Daemon. Jul 08 17:30:00 rivo systemd[1]: Starting Music Player Daemon... Jul 08 17:30:00 rivo kernel: aml_spdif_open Jul 08 17:30:00 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jul 08 17:30:00 rivo kernel: spdif_a keep clk continuous Jul 08 17:30:00 rivo kernel: aml_spdif_close Jul 08 17:30:00 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jul 08 17:30:00 rivo sudo[3953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 08 17:30:00 rivo sudo[3953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 08 17:30:00 rivo sudo[3953]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:00 rivo sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 08 17:30:00 rivo sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:00 rivo volumio[3289]: info: Adding METAVOLUMIO REST API Endpoints Jul 08 17:30:00 rivo volumio[3289]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 08 17:30:00 rivo volumio[3289]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 08 17:30:00 rivo volumio[3289]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 08 17:30:00 rivo volumio[3289]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:00 rivo sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Jul 08 17:30:00 rivo sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:00 rivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 08 17:30:00 rivo systemd[1]: mpd.service: Succeeded. Jul 08 17:30:00 rivo systemd[1]: Stopped Music Player Daemon. Jul 08 17:30:00 rivo sudo[3960]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:00 rivo systemd[1]: Starting Music Player Daemon... Jul 08 17:30:00 rivo volumio[3289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:00 rivo volumio[3289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 08 17:30:00 rivo volumio[3289]: info: Preparing CD Folders Jul 08 17:30:00 rivo volumio[3289]: info: Adding CD REST API Endpoints Jul 08 17:30:00 rivo volumio[3289]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 08 17:30:00 rivo volumio[3289]: info: Starting UDEV Watcher for CD Jul 08 17:30:00 rivo volumio[3289]: info: Detecting CD presence with UDEV Jul 08 17:30:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 08 17:30:01 rivo sudo[3965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 08 17:30:01 rivo sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo volumio[3289]: info: [1751988601047] CoreMusicLibrary::Adding element Last_100 Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 17:30:01 rivo volumio[3289]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jul 08 17:30:01 rivo sudo[3965]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:01 rivo volumio[3289]: info: QobuzConnect: Starting Qobuz Connect socket and service Jul 08 17:30:01 rivo volumio[3289]: info: Streaming services startup Jul 08 17:30:01 rivo volumio[3289]: info: Starting Streaming Daemon Jul 08 17:30:01 rivo sudo[3975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 08 17:30:01 rivo sudo[3975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo sudo[3975]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:01 rivo sudo[3978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 08 17:30:01 rivo sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo sudo[3980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 08 17:30:01 rivo sudo[3980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo sudo[3978]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 08 17:30:01 rivo volumio[3289]: info: [1751988601254] CoreMusicLibrary::Adding element Webradio Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 17:30:01 rivo volumio[3289]: info: Initializing BBC Radios Jul 08 17:30:01 rivo systemd[1]: Started Volumio Qobuz Connect Service. Jul 08 17:30:01 rivo sudo[3980]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 08 17:30:01 rivo volumio[3289]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 08 17:30:01 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:01 rivo volumio[3289]: error: Hi Res Audio Failed Login: Missing Login Data Jul 08 17:30:01 rivo volumio[3289]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 08 17:30:01 rivo volumio[3289]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 08 17:30:01 rivo volumio[3289]: info: Initializing Serial Communication on port /dev/ttyS3 Jul 08 17:30:01 rivo volumio[3289]: info: Touch Event Listener Process Starting Jul 08 17:30:01 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jul 08 17:30:01 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jul 08 17:30:01 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jul 08 17:30:01 rivo kernel: aml_spdif_open Jul 08 17:30:01 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jul 08 17:30:01 rivo kernel: spdif_a keep clk continuous Jul 08 17:30:01 rivo kernel: aml_spdif_close Jul 08 17:30:01 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jul 08 17:30:01 rivo volumio[3289]: info: Adding inputs REST Endpoints Jul 08 17:30:01 rivo volumio[3289]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 08 17:30:01 rivo volumio[3289]: info: Scanning Audio Inputs Jul 08 17:30:01 rivo sudo[4003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jul 08 17:30:01 rivo sudo[4003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo volumio[3289]: info: Checking against Known Cards name Jul 08 17:30:01 rivo volumio[3289]: info: Checking against Known Cards name Jul 08 17:30:01 rivo volumio[3289]: info: Checking against Known Cards name Jul 08 17:30:01 rivo volumio[3289]: info: Checking against Known Cards name Jul 08 17:30:01 rivo volumio[3289]: info: Checking against Known Cards name Jul 08 17:30:01 rivo volumio[3289]: info: Adding Server instance for streaming Jul 08 17:30:01 rivo sudo[4014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jul 08 17:30:01 rivo sudo[4014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo sudo[4014]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:01 rivo sudo[4017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jul 08 17:30:01 rivo sudo[4017]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo sudo[4017]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:01 rivo sudo[4020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jul 08 17:30:01 rivo sudo[4020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo sudo[4020]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:01 rivo sudo[4023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jul 08 17:30:01 rivo sudo[4023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:01 rivo sudo[4023]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:01 rivo volumio[3289]: info: Apply VIM3L Onboard LEDs Settings Jul 08 17:30:01 rivo volumio[3289]: info: Turning On Onboard LEDs Jul 08 17:30:02 rivo sudo[4027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 08 17:30:02 rivo sudo[4027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:02 rivo volumio[3289]: info: Starting CEC Event listener Jul 08 17:30:02 rivo volumio[3289]: info: Volumio Calling Home Jul 08 17:30:02 rivo sudo[4027]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:02 rivo sudo[4031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 08 17:30:02 rivo sudo[4031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:02 rivo sudo[4031]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:02 rivo volumio[3289]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jul 08 17:30:02 rivo volumio[3289]: info: Stopping AccessToken refresher cron for QOBUZ Jul 08 17:30:02 rivo volumio[3289]: info: AccessToken refresher cron started for QOBUZ Jul 08 17:30:02 rivo volumio[3289]: info: Stopping AccessToken refresher cron Jul 08 17:30:02 rivo volumio[3289]: info: AccessToken refresher cron started Jul 08 17:30:02 rivo volumio[3289]: info: Adding TIDAL REST API Endpoints Jul 08 17:30:02 rivo volumio[3289]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 08 17:30:02 rivo volumio[3289]: info: Adding QOBUZ REST API Endpoints Jul 08 17:30:02 rivo volumio[3289]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 08 17:30:02 rivo volumio[3289]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jul 08 17:30:02 rivo volumio[3289]: info: Serial port opened successfully Jul 08 17:30:02 rivo volumio[3289]: info: Sending serial start messages Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:02 rivo volumio[3289]: info: Reporting MCU Network Status: 1 Jul 08 17:30:02 rivo volumio[3289]: info: MP1 GPIO: Signalled system ready via GPIO Jul 08 17:30:02 rivo volumio[3289]: error: Cannot start Volumio Streaming Daemon Jul 08 17:30:02 rivo volumio[3289]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 08 17:30:02 rivo volumio[3289]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 08 17:30:02 rivo volumio[3289]: info: Bluetooth name changed to Rivo Jul 08 17:30:02 rivo volumio[3289]: info: MPD Permissions set Jul 08 17:30:02 rivo volumio[3289]: info: MPD Permissions set Jul 08 17:30:02 rivo volumio[3289]: info: Upmpdcli Daemon Started Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jul 08 17:30:02 rivo volumio[3289]: info: Setting Device Volume Override Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 08 17:30:02 rivo volumio[3289]: info: Updating Volume Controller Parameters: Device: 0,2 Name: S/PDIF + AES/EBU Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 08 17:30:02 rivo volumio[3289]: info: Disabling external Volume Control Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:02 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:30:02 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:02 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:02 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:30:02 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:30:02 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:02 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:03 rivo volumio[3289]: info: Executing endpoint qc_getconfig Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.083 [3987.3987] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 08 17:30:03 rivo volumio[3289]: info: Volumio called home Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 08 17:30:03 rivo volumio[3289]: info: Setting Additional System Software info: Hardware Revision: 2.1 Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 08 17:30:03 rivo volumio[3289]: info: Setting HW Firmware info: undefined Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 08 17:30:03 rivo volumio[3289]: info: Setting HW Version info: 2.1 Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 08 17:30:03 rivo volumio[3289]: info: Setting Additional System Software info: Hardware Revision: 2.1, Firmware Version: 0.3.4 Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 08 17:30:03 rivo volumio[3289]: info: Setting HW Firmware info: 0.3.4 Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 08 17:30:03 rivo volumio[3289]: info: Setting HW Version info: 2.1 Jul 08 17:30:03 rivo volumio[3289]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 08 17:30:03 rivo volumio[3289]: info: MCU Signalled Headphone Mode Disabled Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 08 17:30:03 rivo volumio[3289]: info: MCU Signalled Sleep Mode Disabled Jul 08 17:30:03 rivo volumio[3289]: info: Enabling Advanced system settings configuration Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jul 08 17:30:03 rivo volumio[3289]: info: Additional UI Settings Added for plugin music_service/inputs Jul 08 17:30:03 rivo volumio[3289]: info: MCU Signalled Auto Boot Mode On Power Disabled Jul 08 17:30:03 rivo sudo[4053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jul 08 17:30:03 rivo sudo[4053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:03 rivo volumio[3289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 08 17:30:03 rivo kernel: fb: osd[0] enable: 1 (Xorg) Jul 08 17:30:03 rivo sudo[4053]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:03 rivo volumio[3289]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 08 17:30:03 rivo volumio[3289]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.294 [3987.3987] INFO VolumeManager: [0xac3448f0]: Setting new playback volume: 75 Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.294 [3987.3987] INFO VolumeManager: [0xac3448f0]: Setting new mute state: 0 Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.294 [3987.3987] INFO QobuzConnect: [0xac3452c0]: Client initialized! Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.294 [3987.3987] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.345 [3987.3987] INFO LocalConfigManager: [0xac3441d0]: Starting Local Configuration server Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.345 [3987.3987] INFO SampleApp: Starting Local configuration server Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.346 [3987.3987] INFO SampleApp: Playback volume changed: 75 Jul 08 17:30:03 rivo qobuz-connect[3987]: 20250708 17:30:03.346 [3987.3987] INFO SampleApp: Connected to UNIX socket client 0xac339ed8 Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:03 rivo volumio[3289]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jul 08 17:30:03 rivo volumio[3289]: 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 08 17:30:03 rivo sudo[4069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jul 08 17:30:03 rivo sudo[4069]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:03 rivo sudo[4069]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:03 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:03 rivo volumio[3289]: info: Starting Shairport Sync Jul 08 17:30:03 rivo volumio[3289]: info: Starting Shairport Sync Jul 08 17:30:03 rivo volumio[3289]: info: Starting Shairport Sync Jul 08 17:30:03 rivo sudo[4072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 17:30:03 rivo sudo[4072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:03 rivo volumio[3289]: info: Adding Inputs via Serial API Jul 08 17:30:03 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:30:03 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:30:03 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:03 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:03 rivo sudo[4075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 17:30:03 rivo sudo[4075]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:03 rivo systemd[1]: shairport-sync.service: Succeeded. Jul 08 17:30:03 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 08 17:30:03 rivo mpd[3973]: Jul 08 17:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:03 rivo sudo[4077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 08 17:30:03 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:03 rivo sudo[4077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:03 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 08 17:30:03 rivo sudo[4072]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:03 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 08 17:30:03 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 08 17:30:03 rivo systemd[1]: shairport-sync.service: Succeeded. Jul 08 17:30:03 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 08 17:30:03 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:03 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:04 rivo sudo[4082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 08 17:30:04 rivo sudo[4082]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:04 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 08 17:30:04 rivo sudo[4075]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:04 rivo sudo[4077]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:04 rivo systemd[1]: Started Music Player Daemon. Jul 08 17:30:04 rivo sudo[3956]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:04 rivo sudo[3934]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:04 rivo systemd[1]: Started Volumio Bluetooth Module. Jul 08 17:30:04 rivo sudo[4082]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:04 rivo volumio[3289]: info: Shairport-Sync Started Jul 08 17:30:04 rivo volumio[3289]: Error adding Membership: Error: addMembership EINVAL Jul 08 17:30:04 rivo sudo[4091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 08 17:30:04 rivo sudo[4091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:04 rivo volumiobt[4088]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 08 17:30:04 rivo sudo[4091]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:04 rivo volumio[3289]: Upnp client error: Error: This socket has been ended by the other party Jul 08 17:30:04 rivo volumio[3289]: info: Volumio BT Module successfully started Jul 08 17:30:04 rivo volumio[3289]: info: Completed starting Core Plugins Jul 08 17:30:04 rivo volumio[3289]: info: ------------------------------------------- Jul 08 17:30:04 rivo volumio[3289]: info: ----- MyVolumio plugins startup ---- Jul 08 17:30:04 rivo volumio[3289]: info: ------------------------------------------- Jul 08 17:30:04 rivo volumio[3289]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 08 17:30:04 rivo volumio[3289]: info: Shairport-Sync Started Jul 08 17:30:04 rivo volumio[3289]: info: Shairport-Sync Started Jul 08 17:30:04 rivo volumio[3289]: info: CoreCommandRouter::servicePushState Jul 08 17:30:04 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:04 rivo volumio[3289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Jul 08 17:30:04 rivo volumio[3289]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 08 17:30:04 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 17:30:04 rivo volumio[3289]: error: MPD error: The expression evaluated to a falsy value: Jul 08 17:30:04 rivo volumio[3289]: assert.ok(self.idling) Jul 08 17:30:04 rivo volumio[3289]: error: The expression evaluated to a falsy value: Jul 08 17:30:04 rivo volumio[3289]: assert.ok(self.idling) Jul 08 17:30:04 rivo volumio[3289]: info: MPD running with PID3973 Jul 08 17:30:04 rivo volumio[3289]: ,establishing connection Jul 08 17:30:04 rivo volumio[3289]: error: updateQueue error: null Jul 08 17:30:04 rivo volumio[3289]: info: MCU Signalled Playback Inactive Jul 08 17:30:04 rivo volumio[3289]: error: updateQueue error: null Jul 08 17:30:04 rivo volumio[3933]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 08 17:30:04 rivo volumio[3289]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 3 Jul 08 17:30:04 rivo volumio[3289]: info: Turning Off Onboard LEDs Jul 08 17:30:04 rivo sudo[4114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 08 17:30:04 rivo sudo[4114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:04 rivo sudo[4114]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:04 rivo sudo[4117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 08 17:30:04 rivo sudo[4117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:05 rivo sudo[4117]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:05 rivo volumio[3289]: info: Onboard LEDs initialized Jul 08 17:30:05 rivo sudo[4120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 08 17:30:05 rivo sudo[4120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:05 rivo volumiobt[4088]: pulseaudio: no process found Jul 08 17:30:05 rivo sudo[4120]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:05 rivo volumio[3289]: 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 08 17:30:05 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:05 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:05 rivo dbus-daemon[2844]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=4124 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Jul 08 17:30:05 rivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 08 17:30:05 rivo volumio[3289]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 17:30:05 rivo kernel: IPVS: Creating netns size=1928 id=3 Jul 08 17:30:05 rivo dbus-daemon[2844]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 08 17:30:05 rivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 08 17:30:05 rivo rtkit-daemon[4125]: Successfully called chroot. Jul 08 17:30:05 rivo rtkit-daemon[4125]: Successfully dropped privileges. Jul 08 17:30:05 rivo rtkit-daemon[4125]: Successfully limited resources. Jul 08 17:30:05 rivo rtkit-daemon[4125]: Running. Jul 08 17:30:05 rivo dbus-daemon[2844]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=4125 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Jul 08 17:30:05 rivo rtkit-daemon[4125]: Watchdog thread running. Jul 08 17:30:05 rivo rtkit-daemon[4125]: Canary thread running. Jul 08 17:30:05 rivo systemd[1]: Starting Authorization Manager... Jul 08 17:30:05 rivo volumio[3289]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 08 17:30:05 rivo polkitd[4128]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 08 17:30:05 rivo dbus-daemon[2844]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 08 17:30:05 rivo systemd[1]: Started Authorization Manager. Jul 08 17:30:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 08 17:30:06 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Jul 08 17:30:06 rivo volumio[3933]: ...............++++ Jul 08 17:30:06 rivo pulseaudio[4124]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 08 17:30:06 rivo pulseaudio[4124]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 08 17:30:06 rivo volumiobt[4088]: Applying permissions Jul 08 17:30:06 rivo volumiobt[4088]: Setting BT discoverable and pairable Jul 08 17:30:06 rivo bluetoothd[3426]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Jul 08 17:30:06 rivo bluetoothd[3426]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Jul 08 17:30:06 rivo pulseaudio[4124]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jul 08 17:30:06 rivo volumiobt[4088]: Agent registered Jul 08 17:30:06 rivo volumiobt[4088]: [bluetooth]# -e power on Jul 08 17:30:06 rivo volumiobt[4088]: Invalid command in menu main: -e Jul 08 17:30:06 rivo volumiobt[4088]: Jul 08 17:30:06 rivo volumiobt[4088]: Use "help" for a list of available commands in a menu. Jul 08 17:30:06 rivo volumiobt[4088]: Use "menu " if you want to enter any submenu. Jul 08 17:30:06 rivo volumiobt[4088]: Use "back" if you want to return to menu main. Jul 08 17:30:06 rivo volumiobt[4088]: [bluetooth]# agent on Jul 08 17:30:06 rivo volumiobt[4088]: Agent is already registered Jul 08 17:30:06 rivo volumiobt[4088]: [bluetooth]# discoverable on Jul 08 17:30:06 rivo volumiobt[4088]: [bluetooth]# pairable on Jul 08 17:30:06 rivo volumiobt[4088]: [bluetooth]# agent NoInputNoOutput Jul 08 17:30:06 rivo volumiobt[4088]: Agent is already registered Jul 08 17:30:06 rivo volumiobt[4088]: [bluetooth]# default-agent Jul 08 17:30:06 rivo volumiobt[4088]: [bluetooth]# quit Jul 08 17:30:06 rivo volumio[3289]: info: MRS: Getting audio outputs on start Jul 08 17:30:06 rivo volumio[3289]: info: MRS: Requesting all other devices output Jul 08 17:30:06 rivo volumiobt[4088]: [59B blob data] Jul 08 17:30:07 rivo volumio[3289]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 5 Jul 08 17:30:08 rivo volumio-remote-updater[2836]: [2025-07-08 17:30:08] [connect] Successful connection Jul 08 17:30:08 rivo volumio-remote-updater[2836]: [2025-07-08 17:30:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751988608 101 Jul 08 17:30:08 rivo volumio[3289]: 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: 6 Jul 08 17:30:09 rivo kernel: fb: mem_free_work, free memory: addr:800000 Jul 08 17:30:10 rivo systemd[1]: systemd-timedated.service: Succeeded. Jul 08 17:30:11 rivo volumio[3289]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 7 Jul 08 17:30:12 rivo volumio[3289]: info: Checking for updated MCU Firmware Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 17:30:12 rivo volumio[3289]: info: Firware on device is on latest version, no need to update Jul 08 17:30:12 rivo volumio[3289]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 8 Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 17:30:12 rivo volumio[3289]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 08 17:30:12 rivo volumio[3289]: info: Received Get System Info Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:12 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: Listing playlists Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 17:30:12 rivo volumio[3289]: info: Received Get System Info Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:12 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 17:30:12 rivo volumio[3289]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 08 17:30:12 rivo volumio[3289]: info: Received Get System Info Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:12 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:12 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:12 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:12 rivo volumio[3289]: info: Listing playlists Jul 08 17:30:13 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 17:30:13 rivo volumio[3289]: info: Received Get System Info Jul 08 17:30:13 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:13 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:13 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:13 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:13 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:13 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 08 17:30:13 rivo volumio[3289]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 08 17:30:15 rivo volumio[3289]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 08 17:30:15 rivo volumio[3289]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 08 17:30:15 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:15 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:15 rivo volumio[3289]: info: Starting MyVolumio Remote Streaming Endpoints Jul 08 17:30:15 rivo volumio[3289]: info: MyVolumio login type: Token Jul 08 17:30:15 rivo volumio[3289]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 08 17:30:15 rivo volumio[3289]: 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 08 17:30:15 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 08 17:30:15 rivo volumio[3289]: info: MyVolumio login type: Token Jul 08 17:30:15 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 08 17:30:15 rivo volumio[3289]: info: MyVolumio login type: Token Jul 08 17:30:15 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 08 17:30:15 rivo volumio[3289]: info: Executing endpoint metavolumio Jul 08 17:30:15 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 17:30:15 rivo volumio[3289]: info: Executing endpoint metavolumio Jul 08 17:30:15 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 17:30:15 rivo volumio[3289]: info: Executing endpoint metavolumio Jul 08 17:30:15 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 17:30:15 rivo volumio[3289]: error: MyVolumio Custom Token format not valid, refreshing it Jul 08 17:30:15 rivo volumio[3289]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 08 17:30:17 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 08 17:30:17 rivo volumio[3289]: info: MyVolumio login type: Token Jul 08 17:30:17 rivo volumio[3289]: info: MyVolumio token set successfully Jul 08 17:30:17 rivo volumio[3289]: info: MYVOLUMIO: Adding device Jul 08 17:30:17 rivo volumio[3289]: info: MYVOLUMIO: Evaluating Server Jul 08 17:30:18 rivo volumio[3289]: info: MyVolumio status changed Jul 08 17:30:18 rivo volumio[3289]: info: Streaming services startup Jul 08 17:30:18 rivo volumio[3289]: info: Starting Streaming Daemon Jul 08 17:30:18 rivo volumio[3289]: info: Removing browser output: myVolumio user plan is not superstar Jul 08 17:30:18 rivo volumio[3289]: info: Removing audio output: Jul 08 17:30:18 rivo volumio[3289]: info: Stoppping Tunnel 1 Jul 08 17:30:18 rivo sudo[4181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 08 17:30:18 rivo sudo[4181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:18 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 17:30:18 rivo volumio[3289]: info: Received Get System Info Jul 08 17:30:18 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:18 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:18 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:18 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:18 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:18 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:18 rivo sudo[4184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 08 17:30:18 rivo sudo[4184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:18 rivo sudo[4181]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:18 rivo volumio[3289]: error: Cannot start Volumio Streaming Daemon Jul 08 17:30:18 rivo volumio[3289]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 08 17:30:18 rivo volumio[3289]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 08 17:30:18 rivo sudo[4184]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:18 rivo volumio[3289]: info: Remote SSH Stopped Jul 08 17:30:18 rivo volumio[3289]: info: Setting Geolocation for MyVolumio to eu8 Jul 08 17:30:18 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:18 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:18 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:19 rivo volumio[3289]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 08 17:30:19 rivo volumio[3289]: info: Updating MyVolumio device info Jul 08 17:30:19 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:19 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:19 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:20 rivo volumio[3289]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 08 17:30:23 rivo volumio[3933]: .......................................................................................................................................................................................................................++++ Jul 08 17:30:23 rivo volumio[3933]: e is 65537 (0x010001) Jul 08 17:30:23 rivo volumio[3933]: writing RSA key Jul 08 17:30:23 rivo volumio[3289]: info: MYVOLUMIO: Adding device Jul 08 17:30:23 rivo volumio[3289]: info: MYVOLUMIO: Evaluating Server Jul 08 17:30:24 rivo volumio[3289]: error: MyVolumio Plugin failed to start in a timely fashion Jul 08 17:30:24 rivo volumio[3289]: [Metrics] CommandRouter: 48s 987.70ms Jul 08 17:30:24 rivo volumio[3289]: info: CoreCommandRouter::volumiosetStartupVolume Jul 08 17:30:24 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:24 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:24 rivo volumio[3289]: info: CoreCommandRouter::Close All Modals sent Jul 08 17:30:24 rivo volumio[3289]: info: CoreCommandRouter::Close All Modals sent Jul 08 17:30:24 rivo kernel: aml_spdif_open Jul 08 17:30:24 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jul 08 17:30:24 rivo kernel: set normal 512 fs /4 fs Jul 08 17:30:24 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jul 08 17:30:24 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jul 08 17:30:24 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jul 08 17:30:24 rivo kernel: set normal 512 fs /4 fs Jul 08 17:30:24 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jul 08 17:30:24 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jul 08 17:30:24 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 08 17:30:24 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jul 08 17:30:24 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 08 17:30:24 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jul 08 17:30:24 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jul 08 17:30:24 rivo volumio[3289]: info: Setting Geolocation for MyVolumio to eu8 Jul 08 17:30:24 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:24 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 08 17:30:25 rivo volumio[3289]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 08 17:30:25 rivo volumio[3289]: info: Received Get System Version Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 17:30:25 rivo volumio[3289]: info: Received Get System Info Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:25 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:25 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 17:30:25 rivo volumio[3289]: No protocol specified Jul 08 17:30:25 rivo volumio[3289]: xcb_connection_has_error() returned true Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 08 17:30:25 rivo volumio[3289]: info: Updating MyVolumio device info Jul 08 17:30:25 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:26 rivo volumio[3289]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 08 17:30:27 rivo volumio[3289]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 08 17:30:29 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jul 08 17:30:29 rivo kernel: spdif_a keep clk continuous Jul 08 17:30:29 rivo kernel: aml_spdif_close Jul 08 17:30:29 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jul 08 17:30:30 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 08 17:30:30 rivo volumio[3289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 08 17:30:30 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:30 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:31 rivo volumio[3289]: info: BOOT COMPLETED Jul 08 17:30:32 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 17:30:32 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 17:30:32 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 08 17:30:32 rivo volumio-remote-updater[2836]: No test mode Jul 08 17:30:32 rivo volumio-remote-updater[2836]: No alpha test mode Jul 08 17:30:32 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 08 17:30:32 rivo volumio[3289]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 08 17:30:32 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 08 17:30:35 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:35 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 08 17:30:35 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 08 17:30:35 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 08 17:30:35 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 08 17:30:35 rivo volumio[3289]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 17:30:35 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 17:30:51 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:51 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:51 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:51 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:51 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:51 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:51 rivo volumio[3289]: verbose: New Socket.io Connection to 192.168.1.17:3000 from 192.168.1.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 08 17:30:51 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:51 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:51 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 08 17:30:51 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 08 17:30:54 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:54 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:55 rivo volumio[3289]: verbose: New Socket.io Connection to 192.168.1.17:3000 from 192.168.1.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jul 08 17:30:55 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:55 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:55 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 08 17:30:55 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 08 17:30:55 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:55 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:56 rivo sudo[4373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 08 17:30:56 rivo sudo[4373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:56 rivo sudo[4373]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:56 rivo sudo[4375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 08 17:30:56 rivo sudo[4375]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:56 rivo sudo[4375]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:56 rivo volumio[3289]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; SM-P613 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 08 17:30:56 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:56 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:56 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:56 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:56 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:56 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:56 rivo sudo[4382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 08 17:30:56 rivo sudo[4382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:56 rivo sudo[4383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 08 17:30:56 rivo sudo[4383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 17:30:56 rivo sudo[4382]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:56 rivo sudo[4383]: pam_unix(sudo:session): session closed for user root Jul 08 17:30:57 rivo volumio[3289]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; SM-P613 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::volumioGetVisibleSources Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:57 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 08 17:30:57 rivo volumio[3289]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 08 17:30:57 rivo volumio[3289]: info: Received Get System Info Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:57 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:57 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:57 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:57 rivo volumio[3289]: info: Listing playlists Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 08 17:30:57 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 08 17:30:58 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:58 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:58 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 17:30:58 rivo volumio[3289]: info: Received Get System Info Jul 08 17:30:58 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:30:58 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:30:58 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:30:58 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:30:58 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:30:58 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:30:59 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPlay Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::play index undefined Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::startPlaybackTimer Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::clearAddPlayTracks USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand stop took 4 milliseconds Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces system playlist update Jul 08 17:31:00 rivo volumio[3289]: info: Ignoring MPD Status Update Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand clear took 8 milliseconds Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand add "USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac" Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces system playlist update Jul 08 17:31:00 rivo volumio[3289]: info: Ignoring MPD Status Update Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces system playlist update Jul 08 17:31:00 rivo volumio[3289]: info: Ignoring MPD Status Update Jul 08 17:31:00 rivo volumio[3289]: error: updateQueue error: null Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces system playlist update Jul 08 17:31:00 rivo volumio[3289]: info: Ignoring MPD Status Update Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 22ms Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand add "USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac" took 16 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 15ms Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 12ms Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand play Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces system playlist update Jul 08 17:31:00 rivo volumio[3289]: info: Ignoring MPD Status Update Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces system playlist update Jul 08 17:31:00 rivo volumio[3289]: info: Ignoring MPD Status Update Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 39ms Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand play took 32 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 31ms Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 8ms Jul 08 17:31:00 rivo volumio[3289]: info: Executing endpoint metavolumio Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 17:31:00 rivo volumio[3289]: info: Executing endpoint metavolumio Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 17:31:00 rivo volumio[3289]: info: Executing endpoint metavolumio Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 17:31:00 rivo volumio[3289]: info: Received Get System Info Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 08 17:31:00 rivo volumio[3289]: info: Discovery: Getting this device information Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces state update: player Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::getState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand status Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces state update: player Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::getState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand status Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces state update: player Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::getState Jul 08 17:31:00 rivo kernel: aml_spdif_open Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand status Jul 08 17:31:00 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jul 08 17:31:00 rivo kernel: set normal 512 fs /4 fs Jul 08 17:31:00 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jul 08 17:31:00 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jul 08 17:31:00 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jul 08 17:31:00 rivo kernel: set normal 512 fs /4 fs Jul 08 17:31:00 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jul 08 17:31:00 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jul 08 17:31:00 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 08 17:31:00 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jul 08 17:31:00 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 08 17:31:00 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces state update: player Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand status took 31 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::getState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand status Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand status took 52 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand status took 43 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand status took 30 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseTrackInfo Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::servicePushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"580 Kbps","isStreaming":false,"title":"Cheek To Cheek","artist":"Eva Cassidy","album":"Live At Blues Alley","uri":"USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac","trackType":"flac"} Jul 08 17:31:00 rivo volumio[3289]: verbose: CURRENT POSITION 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState stateService play Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState currentStatus stop Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 104ms Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces state update: player Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::getState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand status Jul 08 17:31:00 rivo volumio[3289]: info: Jul 08 17:31:00 rivo volumio[3289]: ---------------------------- MPD announces state update: player Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::getState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand status Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand playlistinfo took 43 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand playlistinfo took 41 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand status took 15 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand status took 9 milliseconds Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseTrackInfo Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseTrackInfo Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseTrackInfo Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseState Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::servicePushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"580 Kbps","isStreaming":false,"title":"Cheek To Cheek","artist":"Eva Cassidy","album":"Live At Blues Alley","uri":"USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac","trackType":"flac"} Jul 08 17:31:00 rivo volumio[3289]: verbose: CURRENT POSITION 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState stateService play Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState currentStatus play Jul 08 17:31:00 rivo volumio[3289]: info: Received an update from plugin. extracting info from payload Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::servicePushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"580 Kbps","isStreaming":false,"title":"Cheek To Cheek","artist":"Eva Cassidy","album":"Live At Blues Alley","uri":"USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac","trackType":"flac"} Jul 08 17:31:00 rivo volumio[3289]: verbose: CURRENT POSITION 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState stateService play Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState currentStatus play Jul 08 17:31:00 rivo volumio[3289]: info: Received an update from plugin. extracting info from payload Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::servicePushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"594 Kbps","isStreaming":false,"title":"Cheek To Cheek","artist":"Eva Cassidy","album":"Live At Blues Alley","uri":"USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac","trackType":"flac"} Jul 08 17:31:00 rivo volumio[3289]: verbose: CURRENT POSITION 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState stateService play Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState currentStatus play Jul 08 17:31:00 rivo volumio[3289]: info: Received an update from plugin. extracting info from payload Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 364ms Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 361ms Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 342ms Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand playlistinfo took 246 milliseconds Jul 08 17:31:00 rivo volumio[3289]: info: sendMpdCommand playlistinfo took 247 milliseconds Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseTrackInfo Jul 08 17:31:00 rivo volumio[3289]: verbose: ControllerMpd::parseTrackInfo Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::servicePushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"Cheek To Cheek","artist":"Eva Cassidy","album":"Live At Blues Alley","uri":"USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac","trackType":"flac"} Jul 08 17:31:00 rivo volumio[3289]: verbose: CURRENT POSITION 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState stateService play Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState currentStatus play Jul 08 17:31:00 rivo volumio[3289]: info: Received an update from plugin. extracting info from payload Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: ControllerMpd::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::servicePushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":249,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"Cheek To Cheek","artist":"Eva Cassidy","album":"Live At Blues Alley","uri":"USB/SAMSUNG/Eva Cassidy/Eva Cassidy - Live At Blues Alley (1997) 16bit 44Khz FLAC/01. Eva Cassidy - Cheek To Cheek.flac","trackType":"flac"} Jul 08 17:31:00 rivo volumio[3289]: verbose: CURRENT POSITION 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState stateService play Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::syncState currentStatus play Jul 08 17:31:00 rivo volumio[3289]: info: Received an update from plugin. extracting info from payload Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreStateMachine::pushState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioPushState Jul 08 17:31:00 rivo volumio[3289]: info: CoreCommandRouter::volumioGetState Jul 08 17:31:00 rivo volumio[3289]: info: CorePlayQueue::getTrack 0 Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 444ms Jul 08 17:31:00 rivo volumio[3289]: info: ------------------------------ 441ms Jul 08 17:31:01 rivo volumio[3289]: info: Signalling Playback active due to playback status change Jul 08 17:31:01 rivo volumio[3289]: info: MCU Signalled Playback Active Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 17:31:05 rivo volumio[3289]: info: Retrieving Cloud Streaming UI Jul 08 17:31:05 rivo volumio[3289]: info: Getting Tidal Cloud Configuration Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 17:31:05 rivo volumio[3289]: info: Getting Qobuz Cloud Configuration Jul 08 17:31:05 rivo volumio[3289]: info: Asking plugin for UI Config Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 17:31:05 rivo volumio[3289]: info: Getting Spotify Cloud Configuration Jul 08 17:31:05 rivo volumio[3289]: info: Asking plugin for UI Config Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 17:31:05 rivo volumio[3289]: info: Saving Spotify Acccount Jul 08 17:31:05 rivo volumio[3289]: info: Got it Jul 08 17:31:05 rivo volumio[3289]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 08 17:31:05 rivo volumio[3289]: info: Got Tidal Cloud Configuration Jul 08 17:31:05 rivo volumio[3289]: info: Got it Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 17:31:05 rivo volumio[3289]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 17:31:06 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 17:31:06 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 08 17:31:07 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 08 17:31:09 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 08 17:31:09 rivo volumio[3289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 08 17:31:09 rivo volumio[3289]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 17:31:09 rivo volumio[3289]: Error: getaddrinfo -3007 Jul 08 17:31:09 rivo volumio[3289]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jul 08 17:31:09 rivo volumio[3289]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jul 08 17:31:09 rivo volumio[3289]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jul 08 17:31:09 rivo volumio[3289]: code: -3007, Jul 08 17:31:09 rivo volumio[3289]: errno: -3007, Jul 08 17:31:09 rivo volumio[3289]: syscall: 'getaddrinfo' Jul 08 17:31:09 rivo volumio[3289]: } Jul 08 17:31:09 rivo volumio[3289]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 17:31:10 rivo sudo[4468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-08 17:30 Jul 08 17:31:10 rivo sudo[4468]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 05:15:38 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="13b9c0f9785de91e97db9573691d3efe"