-- Logs begin at Thu 2023-03-30 16:26:50 UTC, end at Wed 2024-11-20 16:41:47 UTC. --
Nov 20 16:41:01 rivo ntpd[5803]: receive: Unexpected origin timestamp 0xe7d036e2.b14b5687 does not match aorg 0000000000.00000000 from server@151.22.209.90 xmt 0xeae88f9d.772da5f1
Nov 20 16:41:01 rivo ntpd[5803]: receive: Unexpected origin timestamp 0xe7d036e2.b154ecda does not match aorg 0000000000.00000000 from server@212.6.50.243 xmt 0xeae88f9d.77bc9654
Nov 20 16:41:01 rivo systemd[1]: Starting Daily apt download activities...
Nov 20 16:41:01 rivo systemd[1]: Starting Daily man-db regeneration...
Nov 20 16:41:01 rivo systemd[1]: man-db.service: Succeeded.
Nov 20 16:41:01 rivo systemd[1]: Started Daily man-db regeneration.
Nov 20 16:41:02 rivo systemd[1]: apt-daily.service: Succeeded.
Nov 20 16:41:02 rivo systemd[1]: Started Daily apt download activities.
Nov 20 16:41:02 rivo systemd[1]: Starting Daily apt upgrade and clean activities...
Nov 20 16:41:02 rivo volumio[5358]: info: Starting Streaming Service Transparent Proxy
Nov 20 16:41:02 rivo volumio[5358]: info: Loading plugin "tidalconnect"...
Nov 20 16:41:03 rivo volumio[5358]: info: Loading plugin "webradio"...
Nov 20 16:41:03 rivo volumio[5358]: info: Loading plugin "i2s_dacs"...
Nov 20 16:41:03 rivo volumio[5358]: info: I2S DAC not set, start Auto-detection
Nov 20 16:41:03 rivo volumio[5358]: info: Loading plugin "volumiodiscovery"...
Nov 20 16:41:03 rivo volumio[5358]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Nov 20 16:41:03 rivo volumio[5358]: *** WARNING *** Please fix your application to use the native API of Avahi!
Nov 20 16:41:03 rivo node[5358]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Nov 20 16:41:03 rivo volumio[5358]: *** WARNING *** For more information see
Nov 20 16:41:03 rivo volumio[5358]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Nov 20 16:41:03 rivo volumio[5358]: *** WARNING *** Please fix your application to use the native API of Avahi!
Nov 20 16:41:03 rivo volumio[5358]: *** WARNING *** For more information see
Nov 20 16:41:03 rivo node[5358]: *** WARNING *** Please fix your application to use the native API of Avahi!
Nov 20 16:41:03 rivo node[5358]: *** WARNING *** For more information see
Nov 20 16:41:03 rivo node[5358]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Nov 20 16:41:03 rivo node[5358]: *** WARNING *** Please fix your application to use the native API of Avahi!
Nov 20 16:41:03 rivo node[5358]: *** WARNING *** For more information see
Nov 20 16:41:03 rivo volumio[5358]: info: Applying required configuration parameters for plugin volumiodiscovery
Nov 20 16:41:03 rivo volumio[5358]: info: Discovery: Started advertising with name: Rivo
Nov 20 16:41:03 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Nov 20 16:41:03 rivo volumio[5358]: info: Loading plugin "multiroom"...
Nov 20 16:41:03 rivo systemd[1]: apt-daily-upgrade.service: Succeeded.
Nov 20 16:41:03 rivo systemd[1]: Started Daily apt upgrade and clean activities.
Nov 20 16:41:04 rivo volumio[5358]: STREAMING PROXY: Starting server on port 3245
Nov 20 16:41:04 rivo volumio[5358]: Node JS runtime: 14
Nov 20 16:41:05 rivo volumio[5358]: info: Applying required configuration parameters for plugin multiroom
Nov 20 16:41:05 rivo sudo[6210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /volumio/app/plugins/audio_interface/multiroom/scripts/check_snapcast
Nov 20 16:41:05 rivo sudo[6210]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:05 rivo sudo[6210]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:05 rivo sudo[6212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Nov 20 16:41:05 rivo sudo[6212]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:05 rivo sudo[6212]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:05 rivo volumio[5358]: info: MRS: MultiRoom plugin initialized
Nov 20 16:41:05 rivo volumio[5358]: info: MRS: STOPPING SNAPCLIENT
Nov 20 16:41:05 rivo volumio[5358]: info: MRS: Snap server stop
Nov 20 16:41:05 rivo volumio[5358]: info: MRS: STOPPING volumioStreaming
Nov 20 16:41:05 rivo sudo[6231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Nov 20 16:41:05 rivo sudo[6231]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:05 rivo sudo[6232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Nov 20 16:41:05 rivo sudo[6232]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:05 rivo sudo[6235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Nov 20 16:41:05 rivo sudo[6235]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:05 rivo volumio[5358]: info: Loading plugin "outputs"...
Nov 20 16:41:05 rivo sudo[6238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Nov 20 16:41:05 rivo sudo[6238]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:05 rivo sudo[6238]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:05 rivo volumio[5358]: info: Loading plugin "albumart"...
Nov 20 16:41:05 rivo volumio[5358]: info: Plugin example_plugin is not enabled
Nov 20 16:41:05 rivo sudo[6231]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:05 rivo volumio[5358]: info: Loading plugin "hi_res_audio"...
Nov 20 16:41:05 rivo sudo[6232]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:05 rivo sudo[6235]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:06 rivo volumio[5358]: Forking 3 albumart workers
Nov 20 16:41:07 rivo volumio[5358]: Starting albumart workers
Nov 20 16:41:07 rivo volumio[5358]: Starting albumart workers
Nov 20 16:41:07 rivo volumio[5358]: Starting albumart workers
Nov 20 16:41:07 rivo volumio[5358]: info: Applying required configuration parameters for plugin hi_res_audio
Nov 20 16:41:07 rivo volumio[5358]: info: Loading plugin "inputs"...
Nov 20 16:41:08 rivo volumio[5358]: info: Loading plugin "qobuz"...
Nov 20 16:41:09 rivo systemd[1]: systemd-fsckd.service: Succeeded.
Nov 20 16:41:09 rivo volumio-remote-updater[4607]: [2024-11-20 16:41:09] [connect] Successful connection
Nov 20 16:41:10 rivo volumio[5358]: info: Loading plugin "smart_inputs"...
Nov 20 16:41:10 rivo volumio[5358]: info: Loading plugin "tidal"...
Nov 20 16:41:12 rivo volumio[5358]: info: Loading plugin "rivocontrol"...
Nov 20 16:41:12 rivo volumio[5358]: info: Adding this device properties
Nov 20 16:41:12 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties
Nov 20 16:41:12 rivo volumio[5358]: info: Setting Additional Device Volumio Properties: [object Object]
Nov 20 16:41:12 rivo volumio[5358]: info: Loading plugin "updater_comm"...
Nov 20 16:41:12 rivo volumio[5358]: info: Loading plugin "cec_controller"...
Nov 20 16:41:13 rivo volumio[5358]: info: Applying required configuration parameters for plugin cec_controller
Nov 20 16:41:13 rivo volumio[5358]: info: Plugin mpdemulation is not enabled
Nov 20 16:41:13 rivo volumio[5358]: info: Loading plugin "rest_api"...
Nov 20 16:41:13 rivo volumio[5358]: info: Loading plugin "websocket"...
Nov 20 16:41:13 rivo volumio[5358]: info: Loading i18n strings for locale en
Nov 20 16:41:13 rivo volumio[5358]: Updating browse sources language
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::initPlayerControls
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 20 16:41:13 rivo volumio[5358]: Express server listening on port 3000
Nov 20 16:41:13 rivo volumio[5358]: [Metrics] WebUI: 24s 67.52ms
Nov 20 16:41:13 rivo volumio[5358]: info: CoreStateMachine::resetVolumioState
Nov 20 16:41:13 rivo volumio[5358]: info: CoreStateMachine::getcurrentVolume
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::volumioRetrievevolume
Nov 20 16:41:13 rivo volumio[5358]: info: CoreStateMachine::pushState
Nov 20 16:41:13 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::volumioPushState
Nov 20 16:41:13 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:13 rivo sudo[6303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Nov 20 16:41:13 rivo sudo[6303]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:13 rivo volumio[5358]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Nov 20 16:41:13 rivo sudo[6303]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:13 rivo volumio[5358]: info: Completed loading Core Plugins
Nov 20 16:41:13 rivo sudo[6305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 20 16:41:13 rivo sudo[6305]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:13 rivo volumio[5358]: info: Preparing to generate the ALSA configuration file
Nov 20 16:41:13 rivo sudo[6305]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:13 rivo volumio[5358]: info: Cannot read play queue from file
Nov 20 16:41:14 rivo volumio[5358]: info: MRS: Removed streaming files
Nov 20 16:41:14 rivo volumio[5358]: info: MRS: volumioStreaming STOPPED
Nov 20 16:41:14 rivo volumio[5358]: info: MRS: SNAPSERVER STOPPED
Nov 20 16:41:14 rivo volumio[5358]: info: MRS: SNAPCLIENT STOPPED
Nov 20 16:41:14 rivo volumio[5358]: info: Snap server and client installed
Nov 20 16:41:14 rivo volumio[5358]: info: MPD Permissions set
Nov 20 16:41:14 rivo volumio[5358]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Nov 20 16:41:14 rivo volumio[5358]: info: Reading ALSA contributions from plugins.
Nov 20 16:41:14 rivo volumio-remote-updater[4607]: [2024-11-20 16:41:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732120869 101
Nov 20 16:41:14 rivo volumio[5358]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1
Nov 20 16:41:14 rivo volumio[5358]: info: MPD running with PID5902
Nov 20 16:41:14 rivo volumio[5358]: ,establishing connection
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: error: updateQueue error: null
Nov 20 16:41:14 rivo volumio[5358]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Nov 20 16:41:14 rivo volumio[5358]: info: CoreStateMachine::setRepeat null single undefined
Nov 20 16:41:14 rivo volumio[5358]: info: CoreStateMachine::pushState
Nov 20 16:41:14 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::volumioPushState
Nov 20 16:41:14 rivo volumio[5358]: info: CoreStateMachine::setRandom null
Nov 20 16:41:14 rivo volumio[5358]: info: CoreStateMachine::pushState
Nov 20 16:41:14 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::volumioPushState
Nov 20 16:41:14 rivo volumio[5358]: error: updateQueue error: null
Nov 20 16:41:14 rivo volumio[5358]: info: Asound.conf file unchanged, so no further update is needed
Nov 20 16:41:14 rivo volumio[5358]: info: Output device has changed, restarting MPD
Nov 20 16:41:14 rivo sudo[6326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Nov 20 16:41:14 rivo sudo[6326]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:14 rivo sudo[6329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Nov 20 16:41:14 rivo sudo[6329]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:14 rivo volumio[5358]: info: Output device has changed, restarting Shairport Sync
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:14 rivo sudo[6329]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:14 rivo systemd[1]: Started UPnP Renderer front-end to MPD.
Nov 20 16:41:14 rivo sudo[6326]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:14 rivo sudo[6332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Nov 20 16:41:14 rivo sudo[6332]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:14 rivo systemd[1]: Stopping Music Player Daemon...
Nov 20 16:41:14 rivo volumio[5358]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Nov 20 16:41:14 rivo volumio[5358]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo systemd[1]: mpd.service: Succeeded.
Nov 20 16:41:14 rivo systemd[1]: Stopped Music Player Daemon.
Nov 20 16:41:14 rivo systemd[1]: Starting Music Player Daemon...
Nov 20 16:41:14 rivo volumio[5358]: info: ___________ START PLUGINS ___________
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Nov 20 16:41:14 rivo volumio[5358]: info: [1732120874877] CoreMusicLibrary::Adding element Media Servers
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 20 16:41:14 rivo volumio[5358]: Cannot find translation for sourceMedia Servers
Nov 20 16:41:14 rivo kernel: aml_spdif_open
Nov 20 16:41:14 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0
Nov 20 16:41:14 rivo kernel: spdif_a keep clk continuous
Nov 20 16:41:14 rivo kernel: aml_spdif_close
Nov 20 16:41:14 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Nov 20 16:41:14 rivo volumio[5358]: info: Adding Manifest REST API Endpoints
Nov 20 16:41:14 rivo volumio[5358]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui
Nov 20 16:41:14 rivo volumio[5358]: info: Adding METAVOLUMIO REST API Endpoints
Nov 20 16:41:14 rivo volumio[5358]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:14 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:14 rivo sudo[6370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo
Nov 20 16:41:14 rivo sudo[6370]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:14 rivo sudo[6370]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:15 rivo volumio[5358]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Nov 20 16:41:15 rivo volumio[5358]: info: Preparing CD Folders
Nov 20 16:41:15 rivo volumio[5358]: info: Adding CD REST API Endpoints
Nov 20 16:41:15 rivo volumio[5358]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Nov 20 16:41:15 rivo volumio[5358]: info: Starting UDEV Watcher for CD
Nov 20 16:41:15 rivo volumio[5358]: info: Detecting CD presence with UDEV
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Nov 20 16:41:15 rivo volumio[5358]: info: [1732120875749] CoreMusicLibrary::Adding element Last_100
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 20 16:41:15 rivo volumio[5358]: Cannot find translation for sourceMedia Servers
Nov 20 16:41:15 rivo volumio[5358]: info: Starting RAAT Plugin
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections
Nov 20 16:41:15 rivo volumio[5358]: info: Additional UI Settings Added for plugin music_service/raat
Nov 20 16:41:15 rivo volumio[5358]: info: Registering DSP Elements listener and retrieving current ones
Nov 20 16:41:15 rivo volumio[5358]: info: Additional DSP elements updated
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: Updating RAAT Signal Path
Nov 20 16:41:15 rivo volumio[5358]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 20 16:41:15 rivo volumio[5358]: info: Not Reporting Auto name since its the default one
Nov 20 16:41:15 rivo volumio[5358]: info: Streaming services startup
Nov 20 16:41:15 rivo volumio[5358]: info: Starting Streaming Daemon
Nov 20 16:41:15 rivo sudo[6409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Nov 20 16:41:15 rivo sudo[6409]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:15 rivo sudo[6409]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Nov 20 16:41:15 rivo volumio[5358]: info: [1732120875967] CoreMusicLibrary::Adding element Webradio
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 20 16:41:15 rivo volumio[5358]: Cannot find translation for sourceMedia Servers
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 20 16:41:15 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Nov 20 16:41:15 rivo sudo[6417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Nov 20 16:41:15 rivo sudo[6417]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:16 rivo volumio[5358]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Nov 20 16:41:16 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:16 rivo sudo[6417]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:16 rivo volumio[5358]: error: Hi Res Audio Failed Login: Missing Login Data
Nov 20 16:41:16 rivo volumio[5358]: info: Adding HIGHRESAUDIO REST API Endpoints
Nov 20 16:41:16 rivo volumio[5358]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Nov 20 16:41:16 rivo volumio[5358]: info: Initializing Serial Communication on port /dev/ttyS3
Nov 20 16:41:16 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600
Nov 20 16:41:16 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600
Nov 20 16:41:16 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200
Nov 20 16:41:16 rivo volumio[5358]: info: Adding inputs REST Endpoints
Nov 20 16:41:16 rivo volumio[5358]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Nov 20 16:41:16 rivo volumio[5358]: info: Scanning Audio Inputs
Nov 20 16:41:16 rivo kernel: aml_spdif_open
Nov 20 16:41:16 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0
Nov 20 16:41:16 rivo kernel: spdif_a keep clk continuous
Nov 20 16:41:16 rivo kernel: aml_spdif_close
Nov 20 16:41:16 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0
Nov 20 16:41:16 rivo volumio[5358]: info: Checking against Known Cards name
Nov 20 16:41:16 rivo volumio[5358]: info: Checking against Known Cards name
Nov 20 16:41:16 rivo volumio[5358]: info: Checking against Known Cards name
Nov 20 16:41:16 rivo volumio[5358]: info: Checking against Known Cards name
Nov 20 16:41:16 rivo volumio[5358]: info: Checking against Known Cards name
Nov 20 16:41:16 rivo volumio[5358]: info: Adding Server instance for streaming
Nov 20 16:41:16 rivo sudo[6435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0
Nov 20 16:41:16 rivo sudo[6435]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:16 rivo sudo[6435]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:16 rivo sudo[6438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0
Nov 20 16:41:16 rivo sudo[6438]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:16 rivo sudo[6438]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:16 rivo sudo[6441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1
Nov 20 16:41:16 rivo sudo[6441]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:16 rivo sudo[6441]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:16 rivo sudo[6444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1
Nov 20 16:41:16 rivo sudo[6444]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:16 rivo sudo[6444]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:16 rivo volumio[5358]: info: Starting CEC Event listener
Nov 20 16:41:16 rivo volumio[5358]: info: Volumio Calling Home
Nov 20 16:41:16 rivo volumio[5358]: (node:5358) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Nov 20 16:41:16 rivo volumio[5358]: (Use `node --trace-deprecation ...` to show where the warning was created)
Nov 20 16:41:16 rivo volumio[5358]: info: Stopping AccessToken refresher cron for QOBUZ
Nov 20 16:41:16 rivo volumio[5358]: info: AccessToken refresher cron started for QOBUZ
Nov 20 16:41:16 rivo volumio[5358]: info: Stopping AccessToken refresher cron
Nov 20 16:41:16 rivo mpd[6401]: Nov 20 16:41 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Nov 20 16:41:16 rivo mpd[6401]: Nov 20 16:41 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Nov 20 16:41:16 rivo mpd[6401]: Nov 20 16:41 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Nov 20 16:41:16 rivo systemd[1]: Started Music Player Daemon.
Nov 20 16:41:16 rivo sudo[6332]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:16 rivo volumio[5358]: info: AccessToken refresher cron started
Nov 20 16:41:16 rivo volumio[5358]: info: Adding TIDAL REST API Endpoints
Nov 20 16:41:16 rivo volumio[5358]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
Nov 20 16:41:16 rivo volumio[5358]: info: Adding QOBUZ REST API Endpoints
Nov 20 16:41:16 rivo volumio[5358]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Nov 20 16:41:16 rivo volumio[5358]: info: Discovery: adding 38314376-49d3-4e9a-8854-012301386d76
Nov 20 16:41:16 rivo volumio[5358]: info: Discovery: Found device Rivo
Nov 20 16:41:16 rivo volumio[5358]: info: CoreCommandRouter::volumioGetState
Nov 20 16:41:16 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:16 rivo volumio[5358]: info: MRS: Pushing multiroomSync output for this device
Nov 20 16:41:16 rivo volumio[5358]: info: MRS: Pushing multiroomSync output
Nov 20 16:41:16 rivo volumio[5358]: info: Adding audio output:
Nov 20 16:41:16 rivo volumio[5358]: info: Adding audio output:
Nov 20 16:41:16 rivo volumio[5358]: info: Discovery: this is already registered, 38314376-49d3-4e9a-8854-012301386d76
Nov 20 16:41:16 rivo volumio[5358]: info: Discovery: Found device Rivo
Nov 20 16:41:16 rivo volumio[5358]: info: CoreCommandRouter::volumioGetState
Nov 20 16:41:16 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:16 rivo volumio[5358]: info: Serial port opened successfully
Nov 20 16:41:16 rivo volumio[5358]: info: Sending serial start messages
Nov 20 16:41:16 rivo volumio[5358]: info: MP1 GPIO: Signalled system ready via GPIO
Nov 20 16:41:16 rivo volumio[5358]: error: Cannot start Volumio Streaming Daemon
Nov 20 16:41:16 rivo volumio[5358]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Nov 20 16:41:16 rivo volumio[5358]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Nov 20 16:41:16 rivo volumio[5358]: info: RAAT Albumart path created successfully
Nov 20 16:41:16 rivo volumio[5358]: info: Bluetooth name changed to Rivo
Nov 20 16:41:16 rivo volumio[5358]: info: MPD Permissions set
Nov 20 16:41:16 rivo volumio[5358]: info: Upmpdcli Daemon Started
Nov 20 16:41:16 rivo volumio[5358]: Upnp client error: Error: This socket has been ended by the other party
Nov 20 16:41:16 rivo volumio[5358]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Nov 20 16:41:17 rivo volumio[5358]: error: updateQueue error: null
Nov 20 16:41:17 rivo sudo[6470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Nov 20 16:41:17 rivo sudo[6470]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:17 rivo volumio[5358]: info: Volumio called home
Nov 20 16:41:17 rivo systemd[1]: Started RAAT DAEMON.
Nov 20 16:41:17 rivo sudo[6470]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo
Nov 20 16:41:17 rivo volumio[5358]: info: Setting Additional System Software info: Hardware Revision: 2.0
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion
Nov 20 16:41:17 rivo volumio[5358]: info: Setting HW Firmware info: undefined
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion
Nov 20 16:41:17 rivo volumio[5358]: info: Setting HW Version info: 2.0
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo
Nov 20 16:41:17 rivo volumio[5358]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.1.1
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion
Nov 20 16:41:17 rivo volumio[5358]: info: Setting HW Firmware info: 0.1.1
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion
Nov 20 16:41:17 rivo volumio[5358]: info: Setting HW Version info: 2.0
Nov 20 16:41:17 rivo volumio[5358]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Nov 20 16:41:17 rivo volumio[5358]: info: MCU Signalled Headphone Mode Disabled
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:17 rivo volumio[5358]: info: Updating RAAT Signal Path
Nov 20 16:41:17 rivo volumio[5358]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined
Nov 20 16:41:17 rivo kernel: aml_spdif_open
Nov 20 16:41:17 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0
Nov 20 16:41:17 rivo kernel: spdif_a keep clk continuous
Nov 20 16:41:17 rivo kernel: aml_spdif_close
Nov 20 16:41:17 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0
Nov 20 16:41:17 rivo kernel: aml_spdif_open
Nov 20 16:41:17 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0
Nov 20 16:41:17 rivo kernel: spdif_a keep clk continuous
Nov 20 16:41:17 rivo kernel: aml_spdif_close
Nov 20 16:41:17 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0
Nov 20 16:41:17 rivo volumio[5358]: info: Raat Daemon started successfully
Nov 20 16:41:17 rivo volumio[5358]: info: Starting Shairport Sync
Nov 20 16:41:17 rivo volumio[5358]: info: Starting Shairport Sync
Nov 20 16:41:17 rivo sudo[6507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Nov 20 16:41:17 rivo sudo[6507]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:17 rivo volumio[5358]: info: Starting Shairport Sync
Nov 20 16:41:17 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Nov 20 16:41:17 rivo systemd[1]: shairport-sync.service: Succeeded.
Nov 20 16:41:17 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Nov 20 16:41:17 rivo sudo[6510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Nov 20 16:41:17 rivo sudo[6510]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:17 rivo volumio[5358]: info: MRS: Found cast device: BRAVIA-4K-VH22-7da4481f9f535d87be1b4c07d8ea0c99
Nov 20 16:41:17 rivo volumio[5358]: info: Adding audio output:
Nov 20 16:41:17 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Nov 20 16:41:17 rivo sudo[6513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Nov 20 16:41:17 rivo sudo[6513]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:17 rivo sudo[6507]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:17 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Nov 20 16:41:17 rivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Nov 20 16:41:17 rivo systemd[1]: shairport-sync.service: Succeeded.
Nov 20 16:41:17 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Nov 20 16:41:17 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Nov 20 16:41:17 rivo sudo[6510]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:17 rivo sudo[6513]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:17 rivo volumio[5358]: info: Executing endpoint restartRAATSocket
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Nov 20 16:41:17 rivo volumio[5358]: info: Shairport-Sync Started
Nov 20 16:41:17 rivo volumio[5358]: Error adding Membership: Error: addMembership EINVAL
Nov 20 16:41:17 rivo volumio[5358]: info: Shairport-Sync Started
Nov 20 16:41:17 rivo volumio[5358]: info: Shairport-Sync Started
Nov 20 16:41:17 rivo volumio[5358]: info: Adding Inputs via Serial API
Nov 20 16:41:17 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:17 rivo volumio[5358]: info: CoreCommandRouter::volumioGetState
Nov 20 16:41:17 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:17 rivo sudo[6558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Nov 20 16:41:17 rivo sudo[6558]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:17 rivo volumio[5358]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3
Nov 20 16:41:18 rivo volumio[5358]: info: CoreCommandRouter::servicePushState
Nov 20 16:41:18 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:18 rivo volumio[5358]: verbose: STATE SERVICE {"status":"play","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":true,"channels":2}
Nov 20 16:41:18 rivo volumio[5358]: verbose: CURRENT POSITION 0
Nov 20 16:41:18 rivo volumio[5358]: info: CoreStateMachine::syncState stateService play
Nov 20 16:41:18 rivo volumio[5358]: info: CoreStateMachine::syncState currentStatus stop
Nov 20 16:41:18 rivo volumio[5358]: info: CoreCommandRouter::volumiosetSourceActiveno-source
Nov 20 16:41:18 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 20 16:41:18 rivo volumio[5358]: Cannot find translation for sourceMedia Servers
Nov 20 16:41:18 rivo systemd[1]: Started Volumio Bluetooth Module.
Nov 20 16:41:18 rivo sudo[6558]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:18 rivo volumio[5358]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4
Nov 20 16:41:18 rivo sudo[6572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/*
Nov 20 16:41:18 rivo sudo[6572]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:18 rivo volumiobt[6560]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory
Nov 20 16:41:18 rivo sudo[6572]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:18 rivo volumio[5358]: info: Volumio BT Module successfully started
Nov 20 16:41:18 rivo kernel: audio locker is not init
Nov 20 16:41:18 rivo volumio[6333]: Generating RSA private key, 4096 bit long modulus (2 primes)
Nov 20 16:41:19 rivo volumio[5358]: info: TidalConnect service stoped!
Nov 20 16:41:19 rivo volumio[5358]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Nov 20 16:41:19 rivo volumio[5358]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Nov 20 16:41:19 rivo sudo[6622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio
Nov 20 16:41:19 rivo sudo[6622]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:19 rivo sudo[6623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Nov 20 16:41:19 rivo sudo[6623]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:19 rivo volumiobt[6560]: pulseaudio: no process found
Nov 20 16:41:19 rivo sudo[6622]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:19 rivo systemd[1]: Started Volumio Tidal Connect Service.
Nov 20 16:41:19 rivo sudo[6623]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:19 rivo dbus-daemon[4628]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.20' (uid=1000 pid=6650 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel")
Nov 20 16:41:19 rivo systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Nov 20 16:41:19 rivo volumio[5358]: ------------------------------------ BT MESSAGE: BT STATUS: running
Nov 20 16:41:19 rivo kernel: IPVS: Creating netns size=1928 id=3
Nov 20 16:41:19 rivo dbus-daemon[4628]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Nov 20 16:41:19 rivo systemd[1]: Started RealtimeKit Scheduling Policy Service.
Nov 20 16:41:19 rivo rtkit-daemon[6651]: Successfully called chroot.
Nov 20 16:41:19 rivo rtkit-daemon[6651]: Successfully dropped privileges.
Nov 20 16:41:19 rivo rtkit-daemon[6651]: Successfully limited resources.
Nov 20 16:41:19 rivo rtkit-daemon[6651]: Running.
Nov 20 16:41:19 rivo rtkit-daemon[6651]: Canary thread running.
Nov 20 16:41:19 rivo rtkit-daemon[6651]: Watchdog thread running.
Nov 20 16:41:19 rivo dbus-daemon[4628]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.21' (uid=0 pid=6651 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel")
Nov 20 16:41:19 rivo systemd[1]: Starting Authorization Manager...
Nov 20 16:41:19 rivo volumio[5358]: ------------------------------------ BT MESSAGE: BT STATUS: running
Nov 20 16:41:19 rivo polkitd[6683]: started daemon version 0.105 using authority implementation `local' version `0.105'
Nov 20 16:41:19 rivo dbus-daemon[4628]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Nov 20 16:41:19 rivo systemd[1]: Started Authorization Manager.
Nov 20 16:41:19 rivo volumio[5358]: info: Executing endpoint tc_getconfig
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Nov 20 16:41:19 rivo vtcs[6628]: STARTING TidalConnect services, version: 1.1.7.5
Nov 20 16:41:19 rivo vtcs[6628]: STARTED TidalConnect services.
Nov 20 16:41:19 rivo volumio[5358]: info: Executing endpoint tc_connect
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Nov 20 16:41:19 rivo volumio[5358]: info: Connecting to TidalConnect
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::servicePushState
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::pushState
Nov 20 16:41:19 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::volumioPushState
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output update for this device
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output
Nov 20 16:41:19 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:19 rivo volumio[5358]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Nov 20 16:41:19 rivo volumio[5358]: verbose: CURRENT POSITION 0
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::syncState stateService stop
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::syncState currentStatus play
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::volumioPushState
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output update for this device
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::stPlaybackTimer
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::servicePushState
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::pushState
Nov 20 16:41:19 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::volumioPushState
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output update for this device
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output
Nov 20 16:41:19 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:19 rivo volumio[5358]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Nov 20 16:41:19 rivo volumio[5358]: verbose: CURRENT POSITION 0
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::syncState stateService stop
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::syncState currentStatus stop
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::pushState
Nov 20 16:41:19 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::volumioPushState
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output update for this device
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output
Nov 20 16:41:19 rivo volumio[5358]: info: No code
Nov 20 16:41:19 rivo volumio[5358]: info: CoreStateMachine::pushState
Nov 20 16:41:19 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:19 rivo volumio[5358]: info: CoreCommandRouter::volumioPushState
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output update for this device
Nov 20 16:41:19 rivo volumio[5358]: info: MRS: Pushing multiroomSync output
Nov 20 16:41:19 rivo volumio[6333]: .........++++
Nov 20 16:41:19 rivo volumio[5358]: info: Updating RAAT Signal Path
Nov 20 16:41:20 rivo volumio[5358]: info: Updating RAAT Signal Path
Nov 20 16:41:20 rivo volumio[5358]: info: Updating RAAT Signal Path
Nov 20 16:41:20 rivo volumio[5358]: info: Updating RAAT Signal Path
Nov 20 16:41:20 rivo volumio[5358]: info: Updating RAAT Signal Path
Nov 20 16:41:20 rivo pulseaudio[6650]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Nov 20 16:41:20 rivo pulseaudio[6650]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Nov 20 16:41:20 rivo volumiobt[6560]: Applying permissions
Nov 20 16:41:20 rivo volumiobt[6560]: Setting BT discoverable and pairable
Nov 20 16:41:20 rivo bluetoothd[5597]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource
Nov 20 16:41:20 rivo bluetoothd[5597]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink
Nov 20 16:41:20 rivo pulseaudio[6650]: 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
Nov 20 16:41:20 rivo volumio[5358]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux aarch64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 5
Nov 20 16:41:20 rivo volumiobt[6560]: Agent registered
Nov 20 16:41:20 rivo volumiobt[6560]: [bluetooth]# -e power on
Nov 20 16:41:20 rivo volumiobt[6560]: Invalid command in menu main: -e
Nov 20 16:41:20 rivo volumiobt[6560]:
Nov 20 16:41:20 rivo volumiobt[6560]: Use "help" for a list of available commands in a menu.
Nov 20 16:41:20 rivo volumiobt[6560]: Use "menu " if you want to enter any submenu.
Nov 20 16:41:20 rivo volumiobt[6560]: Use "back" if you want to return to menu main.
Nov 20 16:41:20 rivo volumiobt[6560]: [bluetooth]# agent on
Nov 20 16:41:20 rivo volumiobt[6560]: Agent is already registered
Nov 20 16:41:20 rivo volumiobt[6560]: [bluetooth]# discoverable on
Nov 20 16:41:20 rivo volumiobt[6560]: [bluetooth]# pairable on
Nov 20 16:41:20 rivo volumiobt[6560]: [bluetooth]# agent NoInputNoOutput
Nov 20 16:41:20 rivo volumiobt[6560]: Agent is already registered
Nov 20 16:41:20 rivo volumiobt[6560]: [bluetooth]# default-agent
Nov 20 16:41:20 rivo volumiobt[6560]: [bluetooth]# quit
Nov 20 16:41:20 rivo volumiobt[6560]: [59B blob data]
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::volumioGetVisibleSources
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Nov 20 16:41:20 rivo volumio[5358]: info: Received Get System Info
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 20 16:41:20 rivo volumio[5358]: info: Discovery: Getting this device information
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::volumioGetState
Nov 20 16:41:20 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 20 16:41:20 rivo volumio-remote-updater[4607]: No test mode
Nov 20 16:41:20 rivo volumio-remote-updater[4607]: No alpha test mode
Nov 20 16:41:20 rivo volumio[5358]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object]
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings
Nov 20 16:41:20 rivo volumio[5358]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Nov 20 16:41:20 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Nov 20 16:41:20 rivo volumio[5358]: info: MRS: Getting audio outputs on start
Nov 20 16:41:20 rivo volumio[5358]: info: MRS: Requesting all other devices output
Nov 20 16:41:21 rivo volumio[5358]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
- Fix Multiroom Buffer Sizes
- Increase results for Kana-Kanji Conversion
- Fix for streaming interruptions
NEW ADDITIONS
- Restore MCU default settings when performing a factory reset
","title":"Update v3.779","updateavailable":true}
Nov 20 16:41:21 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Nov 20 16:41:21 rivo systemd[1]: systemd-hostnamed.service: Succeeded.
Nov 20 16:41:22 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Nov 20 16:41:22 rivo volumio[5358]: info: TidalConnect service started!
Nov 20 16:41:22 rivo volumio[5358]: info: Completed starting Core Plugins
Nov 20 16:41:22 rivo volumio[5358]: info: -------------------------------------------
Nov 20 16:41:22 rivo volumio[5358]: info: ----- MyVolumio plugins startup ----
Nov 20 16:41:22 rivo volumio[5358]: info: -------------------------------------------
Nov 20 16:41:22 rivo volumio[5358]: info: [MyVolumio PluginManager] Fetching plans data....
Nov 20 16:41:26 rivo volumio[5358]: info: Checking for updated MCU Firmware
Nov 20 16:41:26 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 20 16:41:26 rivo volumio[5358]: info: Firware on device is on latest version, no need to update
Nov 20 16:41:30 rivo kernel: fb: mem_free_work, free memory: addr:800000
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Nov 20 16:41:30 rivo volumio[5358]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Nov 20 16:41:31 rivo volumio[6333]: ........................................................................................................................................................++++
Nov 20 16:41:31 rivo volumio[6333]: e is 65537 (0x010001)
Nov 20 16:41:31 rivo volumio[6333]: writing RSA key
Nov 20 16:41:32 rivo volumio[5358]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Nov 20 16:41:32 rivo volumio[5358]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Nov 20 16:41:32 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:32 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:32 rivo volumio[5358]: info: Starting MyVolumio Remote Streaming Endpoints
Nov 20 16:41:32 rivo volumio[5358]: info: MyVolumio not started
Nov 20 16:41:32 rivo volumio[5358]: info: Initializing device activation check
Nov 20 16:41:32 rivo volumio[5358]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Nov 20 16:41:32 rivo volumio[5358]: 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'
Nov 20 16:41:36 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Nov 20 16:41:36 rivo volumio[5358]: info: Loading i18n strings for locale it
Nov 20 16:41:36 rivo volumio[5358]: Updating browse sources language
Nov 20 16:41:36 rivo volumio[5358]: Cannot find translation for sourceMedia Servers
Nov 20 16:41:36 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 20 16:41:36 rivo volumio[5358]: Cannot find translation for sourceMedia Servers
Nov 20 16:41:36 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Nov 20 16:41:36 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Nov 20 16:41:36 rivo sudo[6951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Nov 20 16:41:36 rivo sudo[6951]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo volumio[5358]: info: Fetching Streaming Services browse cache
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:36 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:37 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:38 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:38 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Nov 20 16:41:38 rivo volumio[5358]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Nov 20 16:41:38 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Nov 20 16:41:38 rivo volumio[5358]: info: Received Get System Version
Nov 20 16:41:38 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 20 16:41:38 rivo volumio[5358]: info: Received Get System Info
Nov 20 16:41:38 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Nov 20 16:41:38 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Nov 20 16:41:38 rivo volumio[5358]: info: Discovery: Getting this device information
Nov 20 16:41:38 rivo volumio[5358]: info: CoreCommandRouter::volumioGetState
Nov 20 16:41:38 rivo volumio[5358]: info: CorePlayQueue::getTrack 0
Nov 20 16:41:38 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Nov 20 16:41:39 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 8
Nov 20 16:41:39 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f71e000 drv_status 0x0 e_type 69 e_states 0
Nov 20 16:41:39 rivo sudo[6951]: pam_unix(sudo:session): session closed for user root
Nov 20 16:41:42 rivo volumio[5358]: error: MyVolumio Plugin failed to start in a timely fashion
Nov 20 16:41:42 rivo volumio[5358]: info: BOOT COMPLETED
Nov 20 16:41:42 rivo volumio[5358]: [Metrics] CommandRouter: 51s 410.06ms
Nov 20 16:41:42 rivo volumio[5358]: info: CoreCommandRouter::volumiosetStartupVolume
Nov 20 16:41:42 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 20 16:41:42 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:42 rivo volumio[5358]: info: CoreCommandRouter::Close All Modals sent
Nov 20 16:41:42 rivo volumio[5358]: info: CoreCommandRouter::Close All Modals sent
Nov 20 16:41:42 rivo kernel: aml_spdif_open
Nov 20 16:41:42 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0
Nov 20 16:41:42 rivo kernel: set normal 512 fs /4 fs
Nov 20 16:41:42 rivo kernel: set spdifout clk:5644800, mpll:22579200
Nov 20 16:41:42 rivo kernel: get spdifout clk:5644797, mpll:22579186
Nov 20 16:41:42 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000
Nov 20 16:41:42 rivo kernel: set normal 512 fs /4 fs
Nov 20 16:41:42 rivo kernel: set spdifout clk:5644800, mpll:22579200
Nov 20 16:41:42 rivo kernel: get spdifout clk:5644797, mpll:22579186
Nov 20 16:41:42 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0
Nov 20 16:41:42 rivo kernel: hdmitx: hw: set audio
Nov 20 16:41:42 rivo kernel: hdmitx: hw: hdmitx tx_aud_src = 0
Nov 20 16:41:42 rivo kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 148352
Nov 20 16:41:42 rivo kernel: hdmitx: hw: aud_n_para = 8918
Nov 20 16:41:42 rivo kernel: hdmitx: hw: set channel status
Nov 20 16:41:42 rivo kernel: hdmitx: audio: Audio Type: PCM
Nov 20 16:41:42 rivo kernel: hdmitx: audio: set audio param
Nov 20 16:41:42 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1
Nov 20 16:41:42 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0
Nov 20 16:41:42 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1
Nov 20 16:41:42 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable
Nov 20 16:41:43 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Nov 20 16:41:43 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Nov 20 16:41:43 rivo volumio[5358]: No protocol specified
Nov 20 16:41:43 rivo volumio[5358]: xcb_connection_has_error() returned true
Nov 20 16:41:43 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Nov 20 16:41:46 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 20 16:41:46 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 20 16:41:46 rivo volumio[5358]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Nov 20 16:41:47 rivo volumio[5358]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Nov 20 16:41:47 rivo volumio[5358]: TypeError: Cannot create property 'type' on string 'invalid server key'
Nov 20 16:41:47 rivo volumio[5358]: at WebSocket.onError (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:450:16)
Nov 20 16:41:47 rivo volumio[5358]: at WebSocket.emit (events.js:400:28)
Nov 20 16:41:47 rivo volumio[5358]: at ClientRequest.upgrade (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:738:12)
Nov 20 16:41:47 rivo volumio[5358]: at Object.onceWrapper (events.js:520:26)
Nov 20 16:41:47 rivo volumio[5358]: at ClientRequest.emit (events.js:400:28)
Nov 20 16:41:47 rivo volumio[5358]: at Socket.socketOnData (_http_client.js:553:11)
Nov 20 16:41:47 rivo volumio[5358]: at Socket.emit (events.js:400:28)
Nov 20 16:41:47 rivo volumio[5358]: at addChunk (internal/streams/readable.js:293:12)
Nov 20 16:41:47 rivo volumio[5358]: at readableAddChunk (internal/streams/readable.js:267:9)
Nov 20 16:41:47 rivo volumio[5358]: at Socket.Readable.push (internal/streams/readable.js:206:10)
Nov 20 16:41:47 rivo volumio[5358]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23)
Nov 20 16:41:47 rivo volumio[5358]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Nov 20 16:41:47 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable
Nov 20 16:41:47 rivo kernel: audio_ddr_mngr: delay:[0us]; FRDDR_STATUS2: [0x5408add0] [0x5408ae50]
Nov 20 16:41:47 rivo kernel: spdif_a keep clk continuous
Nov 20 16:41:47 rivo kernel: aml_spdif_close
Nov 20 16:41:47 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0
Nov 20 16:41:47 rivo sudo[6969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-20 16:40
Nov 20 16:41:47 rivo sudo[6969]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4"
VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463"
VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7"
VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="rivo"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 06:26:50 PM CEST"
VOLUMIO_VERSION="3.449"
VOLUMIO_HARDWARE="mp1"
VOLUMIO_DEVICENAME="Volumio MP1"
VOLUMIO_VENDOR_MODEL="Volumio Rivo"
VOLUMIO_VENDOR="Volumio"
VOLUMIO_MODEL="Rivo"
VOLUMIO_HASH="36906ec4b3ab2e1c7dfb25a91d95363f"