-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sun 2025-02-16 20:18:43 UTC. -- Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::initPlayerControls Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 20:17:00 motivo volumio[873]: Express server listening on port 3000 Feb 16 20:17:00 motivo volumio[873]: [Metrics] WebUI: 17s 44.68ms Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::resetVolumioState Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::getcurrentVolume Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:00 motivo sudo[1464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 20:17:00 motivo sudo[1464]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:00 motivo sudo[1464]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:00 motivo sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 20:17:00 motivo sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:00 motivo sudo[1467]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:00 motivo volumio[873]: info: Volumio Network Manager: Network status updated: 0 Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:00 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::updateTrackBlock Feb 16 20:17:00 motivo volumio[873]: info: CorePlayQueue::getTrackBlock Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::volumioRetrievevolume Feb 16 20:17:00 motivo systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 16 20:17:00 motivo systemd[1]: Started Daily apt upgrade and clean activities. Feb 16 20:17:00 motivo volumio[873]: info: Motivo Hardware found Feb 16 20:17:00 motivo volumio[873]: info: MRS: Removed streaming files Feb 16 20:17:00 motivo volumio[873]: info: MRS: volumioStreaming STOPPED Feb 16 20:17:00 motivo volumio[873]: info: MRS: SNAPSERVER STOPPED Feb 16 20:17:00 motivo volumio[873]: info: MRS: SNAPCLIENT STOPPED Feb 16 20:17:00 motivo nmbd[791]: [2025/02/16 20:17:00.341798, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 20:17:00 motivo nmbd[791]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 16 20:17:00 motivo systemd[1]: Started Samba NMB Daemon. Feb 16 20:17:00 motivo systemd[1]: Starting Samba Winbind Daemon... Feb 16 20:17:00 motivo volumio-remote-updater[714]: [2025-02-16 20:17:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732811839 101 Feb 16 20:17:00 motivo volumio[873]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:00 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:00 motivo winbindd[1482]: [2025/02/16 20:17:00.602792, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 16 20:17:00 motivo winbindd[1482]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 16 20:17:00 motivo winbindd[1482]: [2025/02/16 20:17:00.616317, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 20:17:00 motivo winbindd[1482]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 16 20:17:00 motivo systemd[1]: Started Samba Winbind Daemon. Feb 16 20:17:00 motivo volumio[873]: info: Reloading queue from file Feb 16 20:17:00 motivo volumio[873]: info: Setting Device type: Raspberry PI Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::setRepeat null single undefined Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:00 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:00 motivo systemd[1]: Starting Samba SMB Daemon... Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::setRandom false Feb 16 20:17:00 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:00 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:00 motivo volumio[873]: info: Completed loading Core Plugins Feb 16 20:17:00 motivo volumio[873]: info: Preparing to generate the ALSA configuration file Feb 16 20:17:00 motivo sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 16 20:17:00 motivo sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:00 motivo volumio[873]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 16 20:17:00 motivo volumio[873]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 16 20:17:00 motivo volumio[873]: info: Reading ALSA contributions from plugins. Feb 16 20:17:00 motivo systemd[1]: Started UPnP Renderer front-end to MPD. Feb 16 20:17:00 motivo volumio[873]: info: Discovery: A device disappeared from network Feb 16 20:17:00 motivo volumio[873]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Feb 16 20:17:00 motivo sudo[1493]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:00 motivo volumio[873]: info: Upmpdcli Daemon Started Feb 16 20:17:00 motivo volumio[873]: info: Asound.conf file unchanged, so no further update is needed Feb 16 20:17:00 motivo volumio[873]: info: Output device has changed, restarting MPD Feb 16 20:17:00 motivo volumio[873]: info: Output device has changed, restarting Shairport Sync Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:00 motivo sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 20:17:00 motivo sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:00 motivo sudo[1499]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:00 motivo sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 20:17:00 motivo sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:00 motivo volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 20:17:00 motivo volumio[873]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 20:17:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:00 motivo systemd[1]: Stopping Music Player Daemon... Feb 16 20:17:01 motivo volumio[873]: info: ___________ START PLUGINS ___________ Feb 16 20:17:01 motivo volumio[873]: info: ControllerMpd::onStart: Initializing MPD Feb 16 20:17:01 motivo volumio[873]: info: Creating MPD Configuration file Feb 16 20:17:01 motivo sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 20:17:01 motivo sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:01 motivo systemd[1]: mpd.service: Succeeded. Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 20:17:01 motivo sudo[1514]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:01 motivo systemd[1]: Stopped Music Player Daemon. Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 20:17:01 motivo volumio[873]: info: [1739737021091] CoreMusicLibrary::Adding element Serveurs Média Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 20:17:01 motivo sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 20:17:01 motivo systemd[1]: Starting Music Player Daemon... Feb 16 20:17:01 motivo sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:01 motivo volumio[873]: info: Adding METAVOLUMIO REST API Endpoints Feb 16 20:17:01 motivo volumio[873]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 16 20:17:01 motivo volumio[873]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 16 20:17:01 motivo volumio[873]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 16 20:17:01 motivo volumio[873]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:01 motivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 16 20:17:01 motivo systemd[1]: mpd.service: Succeeded. Feb 16 20:17:01 motivo smbd[1491]: [2025/02/16 20:17:01.146228, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 16 20:17:01 motivo smbd[1491]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 16 20:17:01 motivo systemd[1]: Stopped Music Player Daemon. Feb 16 20:17:01 motivo systemd[1]: Started Samba SMB Daemon. Feb 16 20:17:01 motivo systemd[1]: Reached target Multi-User System. Feb 16 20:17:01 motivo systemd[1]: Reached target Graphical Interface. Feb 16 20:17:01 motivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 16 20:17:01 motivo systemd[1]: Starting Music Player Daemon... Feb 16 20:17:01 motivo volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:01 motivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 16 20:17:01 motivo systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 16 20:17:01 motivo volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 20:17:01 motivo volumio[873]: info: Preparing CD Folders Feb 16 20:17:01 motivo volumio[873]: info: Adding CD REST API Endpoints Feb 16 20:17:01 motivo volumio[873]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 16 20:17:01 motivo volumio[873]: info: Starting UDEV Watcher for CD Feb 16 20:17:01 motivo volumio[873]: info: Detecting CD presence with UDEV Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 16 20:17:01 motivo sudo[1533]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 20:17:01 motivo sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:01 motivo sudo[1533]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 20:17:01 motivo volumio[873]: info: [1739737021275] CoreMusicLibrary::Adding element Last_100 Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 20:17:01 motivo volumio[873]: info: Starting RAAT Plugin Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 16 20:17:01 motivo volumio[873]: info: Additional UI Settings Added for plugin music_service/raat Feb 16 20:17:01 motivo volumio[873]: info: Registering DSP Elements listener and retrieving current ones Feb 16 20:17:01 motivo volumio[873]: info: Additional DSP elements updated Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:01 motivo volumio[873]: info: Updating RAAT Signal Path Feb 16 20:17:01 motivo volumio[873]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 16 20:17:01 motivo volumio[873]: info: Streaming services startup Feb 16 20:17:01 motivo volumio[873]: info: Starting Streaming Daemon Feb 16 20:17:01 motivo sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 16 20:17:01 motivo sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:01 motivo sudo[1542]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 20:17:01 motivo volumio[873]: info: [1739737021403] CoreMusicLibrary::Adding element Webradio Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 20:17:01 motivo volumio[873]: info: Initializing BBC Radios Feb 16 20:17:01 motivo sudo[1549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 16 20:17:01 motivo sudo[1549]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:01 motivo sudo[1549]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 16 20:17:01 motivo volumio[873]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:01 motivo volumio[873]: error: Hi Res Audio Failed Login: Missing Login Data Feb 16 20:17:01 motivo volumio[873]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 16 20:17:01 motivo volumio[873]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 16 20:17:01 motivo volumio[873]: info: Initializing Serial Communication on port /dev/ttyAMA4 Feb 16 20:17:01 motivo volumio[873]: info: Touch Event Listener Process Starting Feb 16 20:17:01 motivo kernel: uart-pl011 fe201800.serial: no DMA platform data Feb 16 20:17:01 motivo volumio[873]: info: Refreshing QOBUZ token Feb 16 20:17:01 motivo sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Feb 16 20:17:01 motivo sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:01 motivo volumio[873]: info: Adding inputs REST Endpoints Feb 16 20:17:01 motivo volumio[873]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 16 20:17:01 motivo volumio[873]: info: Scanning Audio Inputs Feb 16 20:17:01 motivo volumio[873]: info: Checking against Known Cards name Feb 16 20:17:01 motivo volumio[873]: info: Checking against Known Cards name Feb 16 20:17:01 motivo volumio[873]: info: Adding Server instance for streaming Feb 16 20:17:01 motivo volumio[873]: info: Volumio Calling Home Feb 16 20:17:01 motivo volumio[873]: (node:873) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Feb 16 20:17:01 motivo volumio[873]: (Use `node --trace-deprecation ...` to show where the warning was created) Feb 16 20:17:01 motivo volumio[873]: info: Stopping AccessToken refresher cron Feb 16 20:17:01 motivo volumio[873]: info: AccessToken refresher cron started Feb 16 20:17:01 motivo volumio[873]: info: Adding TIDAL REST API Endpoints Feb 16 20:17:01 motivo volumio[873]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Feb 16 20:17:01 motivo volumio[873]: info: Discovery: adding 197ba707-3fbc-415e-a119-04b75cc6354b Feb 16 20:17:01 motivo volumio[873]: info: Discovery: Found device Motivo Feb 16 20:17:01 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:01 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:01 motivo volumio[873]: info: MRS: Pushing multiroomSync output for this device Feb 16 20:17:01 motivo volumio[873]: info: MRS: Pushing multiroomSync output Feb 16 20:17:01 motivo volumio[873]: info: Adding audio output: Feb 16 20:17:01 motivo volumio[873]: info: Adding audio output: Feb 16 20:17:01 motivo volumio[873]: info: Serial port opened successfully Feb 16 20:17:01 motivo volumio[873]: info: Sending serial start messages Feb 16 20:17:01 motivo volumio[873]: info: Display with brightness control is connected, setting display brightness Feb 16 20:17:01 motivo volumio[873]: info: Setting Display Brightness to 12 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: Reporting MCU Network Status: 0 Feb 16 20:17:02 motivo volumio[873]: error: Cannot start Volumio Streaming Daemon Feb 16 20:17:02 motivo volumio[873]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 16 20:17:02 motivo volumio[873]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 16 20:17:02 motivo volumio[873]: info: RAAT Albumart path created successfully Feb 16 20:17:02 motivo volumio[873]: info: MPD Permissions set Feb 16 20:17:02 motivo volumio[873]: info: MPD Permissions set Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Feb 16 20:17:02 motivo volumio[873]: info: Setting Device Volume Override Feb 16 20:17:02 motivo volumio[873]: info: Applying Volume Override Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 16 20:17:02 motivo volumio[873]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 16 20:17:02 motivo volumio[873]: info: Enabling external Volume Control Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:02 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:02 motivo volumio[873]: info: MRS: Pushing multiroomSync output update for this device Feb 16 20:17:02 motivo volumio[873]: info: MRS: Pushing multiroomSync output Feb 16 20:17:02 motivo volumio[873]: info: Discovery: this is already registered, 197ba707-3fbc-415e-a119-04b75cc6354b Feb 16 20:17:02 motivo volumio[873]: info: Discovery: Found device Motivo Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:02 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:02 motivo volumio[873]: info: Volumio called home Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Feb 16 20:17:02 motivo volumio[873]: info: Setting Additional System Software info: Hardware Revision: 2.3 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Feb 16 20:17:02 motivo volumio[873]: info: Setting HW Firmware info: undefined Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Feb 16 20:17:02 motivo volumio[873]: info: Setting HW Version info: 2.3 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Feb 16 20:17:02 motivo volumio[873]: info: Setting Additional System Software info: Hardware Revision: 2.3, Firmware Version: 0.1.9 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Feb 16 20:17:02 motivo volumio[873]: info: Setting HW Firmware info: 0.1.9 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Feb 16 20:17:02 motivo volumio[873]: info: Setting HW Version info: 2.3 Feb 16 20:17:02 motivo volumio[873]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 16 20:17:02 motivo volumio[873]: info: MCU Signalled Headphone Mode Disabled Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 16 20:17:02 motivo volumio[873]: info: Reporting Headphone State: false Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: Updating RAAT Signal Path Feb 16 20:17:02 motivo volumio[873]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 16 20:17:02 motivo volumio[873]: info: MCU Signalled Sleep Mode Disabled Feb 16 20:17:02 motivo mpd[1538]: Feb 16 20:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 20:17:02 motivo systemd[1]: Started Music Player Daemon. Feb 16 20:17:02 motivo systemd[1]: Startup finished in 13.139s (kernel) + 31.115s (userspace) = 44.255s. Feb 16 20:17:02 motivo volumio[873]: info: Enabling Advanced system settings configuration Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Feb 16 20:17:02 motivo volumio[873]: info: Additional UI Settings Added for plugin music_service/inputs Feb 16 20:17:02 motivo volumio[873]: info: MCU Signalled Auto Boot Mode On Power Disabled Feb 16 20:17:02 motivo sudo[1523]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:02 motivo sudo[1501]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:02 motivo sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Feb 16 20:17:02 motivo sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:02 motivo sudo[1583]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:02 motivo volumio[873]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:02 motivo volumio[873]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Feb 16 20:17:02 motivo volumio[873]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Feb 16 20:17:02 motivo volumio[873]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 16 20:17:02 motivo sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Feb 16 20:17:02 motivo sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:02 motivo sudo[1596]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:02 motivo volumio[873]: info: Access Token successfully retrieved Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 16 20:17:02 motivo volumio[873]: info: [1739737022703] CoreMusicLibrary::Adding element QOBUZ Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 20:17:02 motivo volumio[873]: Cannot find translation for source QOBUZ Feb 16 20:17:02 motivo volumio[873]: info: Stopping AccessToken refresher cron for QOBUZ Feb 16 20:17:02 motivo volumio[873]: info: AccessToken refresher cron started for QOBUZ Feb 16 20:17:02 motivo volumio[873]: info: Adding QOBUZ REST API Endpoints Feb 16 20:17:02 motivo volumio[873]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Feb 16 20:17:02 motivo volumio[873]: error: MPD error: The expression evaluated to a falsy value: Feb 16 20:17:02 motivo volumio[873]: assert.ok(self.idling) Feb 16 20:17:02 motivo volumio[873]: error: The expression evaluated to a falsy value: Feb 16 20:17:02 motivo volumio[873]: assert.ok(self.idling) Feb 16 20:17:02 motivo volumio[873]: info: MPD running with PID1538 Feb 16 20:17:02 motivo volumio[873]: ,establishing connection Feb 16 20:17:02 motivo volumio[873]: info: Starting Shairport Sync Feb 16 20:17:02 motivo volumio[873]: info: Starting Shairport Sync Feb 16 20:17:02 motivo sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 20:17:02 motivo volumio[873]: info: Starting Shairport Sync Feb 16 20:17:02 motivo sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:02 motivo sudo[1613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 20:17:02 motivo sudo[1613]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:02 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 20:17:02 motivo volumio[873]: info: Adding Inputs via Serial API Feb 16 20:17:02 motivo systemd[1]: shairport-sync.service: Succeeded. Feb 16 20:17:02 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:02 motivo volumio[873]: info: Adding Advanced Audio Settings via Serial API Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 16 20:17:02 motivo volumio[873]: info: Additional UI Settings Added for plugin music_service/inputs Feb 16 20:17:02 motivo volumio[873]: info: Adding Advanced Audio Settings via Serial API Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 16 20:17:02 motivo volumio[873]: error: updateQueue error: null Feb 16 20:17:02 motivo sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 20:17:02 motivo sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:02 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:02 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 20:17:02 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 16 20:17:02 motivo systemd[1]: shairport-sync.service: Succeeded. Feb 16 20:17:02 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:02 motivo sudo[1611]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:02 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:02 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:02 motivo volumio[873]: info: Preparing to generate the ALSA configuration file Feb 16 20:17:02 motivo sudo[1613]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:02 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 20:17:02 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 16 20:17:02 motivo systemd[1]: shairport-sync.service: Succeeded. Feb 16 20:17:02 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:02 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:02 motivo sudo[1616]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:02 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:02 motivo volumio[873]: info: Shairport-Sync Started Feb 16 20:17:02 motivo volumio[873]: Error adding Membership: Error: addMembership EINVAL Feb 16 20:17:02 motivo volumio[873]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 16 20:17:02 motivo volumio[873]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 16 20:17:02 motivo volumio[873]: info: Reading ALSA contributions from plugins. Feb 16 20:17:02 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:02 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 20:17:02 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:02 motivo volumio[873]: info: MRS: Pushing multiroomSync output update for this device Feb 16 20:17:03 motivo volumio[873]: info: MRS: Pushing multiroomSync output Feb 16 20:17:03 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:03 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:03 motivo volumio[873]: info: MRS: Pushing multiroomSync output update for this device Feb 16 20:17:03 motivo volumio[873]: info: MRS: Pushing multiroomSync output Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::servicePushState Feb 16 20:17:03 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:03 motivo volumio[873]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received inputs Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::volumiosetSourceActiveno-source Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 20:17:03 motivo volumio[873]: Cannot find translation for source QOBUZ Feb 16 20:17:03 motivo volumio[873]: error: updateQueue error: null Feb 16 20:17:03 motivo volumio[873]: info: Shairport-Sync Started Feb 16 20:17:03 motivo volumio[873]: info: Shairport-Sync Started Feb 16 20:17:03 motivo volumio[1496]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 16 20:17:03 motivo volumio[873]: info: Asound.conf file written Feb 16 20:17:03 motivo sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 16 20:17:03 motivo sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo sudo[1640]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:03 motivo volumio[873]: info: Output device has changed, restarting MPD Feb 16 20:17:03 motivo sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 20:17:03 motivo sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo volumio[873]: info: Output device has changed, restarting Shairport Sync Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:03 motivo sudo[1645]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:03 motivo sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 20:17:03 motivo sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo systemd[1]: Stopping Music Player Daemon... Feb 16 20:17:03 motivo volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 20:17:03 motivo volumio[873]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo systemd[1]: mpd.service: Succeeded. Feb 16 20:17:03 motivo systemd[1]: Stopped Music Player Daemon. Feb 16 20:17:03 motivo volumio[873]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: Preparing to generate the ALSA configuration file Feb 16 20:17:03 motivo volumio[873]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 16 20:17:03 motivo volumio[873]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 16 20:17:03 motivo volumio[873]: info: Reading ALSA contributions from plugins. Feb 16 20:17:03 motivo systemd[1]: Starting Music Player Daemon... Feb 16 20:17:03 motivo volumio[873]: info: MPD Permissions set Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo sudo[1655]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 20:17:03 motivo sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo sudo[1655]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:03 motivo volumio[873]: info: Starting Shairport Sync Feb 16 20:17:03 motivo volumio[873]: info: Asound.conf file unchanged, so no further update is needed Feb 16 20:17:03 motivo volumio[873]: info: Output device has changed, restarting MPD Feb 16 20:17:03 motivo sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 20:17:03 motivo volumio[873]: info: Output device has changed, restarting Shairport Sync Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:03 motivo sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 16 20:17:03 motivo sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo sudo[1669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 16 20:17:03 motivo sudo[1667]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:03 motivo sudo[1669]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 20:17:03 motivo systemd[1]: shairport-sync.service: Succeeded. Feb 16 20:17:03 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:03 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:03 motivo sudo[1665]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:03 motivo volumio[873]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 16 20:17:03 motivo volumio[873]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 16 20:17:03 motivo systemd[1]: mpd.service: Succeeded. Feb 16 20:17:03 motivo systemd[1]: Stopped Music Player Daemon. Feb 16 20:17:03 motivo systemd[1]: Starting Music Player Daemon... Feb 16 20:17:03 motivo volumio[873]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: MPD Permissions set Feb 16 20:17:03 motivo volumio[873]: info: Shairport-Sync Started Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: Starting Shairport Sync Feb 16 20:17:03 motivo sudo[1680]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 16 20:17:03 motivo sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo sudo[1680]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:03 motivo sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 16 20:17:03 motivo sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:03 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 16 20:17:03 motivo systemd[1]: shairport-sync.service: Succeeded. Feb 16 20:17:03 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:03 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 16 20:17:03 motivo sudo[1689]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 20:17:03 motivo volumio[873]: info: Not Reporting Auto name since its the default one Feb 16 20:17:03 motivo volumio[873]: info: Shairport-Sync Started Feb 16 20:17:03 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 16 20:17:04 motivo volumio[873]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 16 20:17:04 motivo sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 16 20:17:04 motivo sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:04 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:04 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:04 motivo sudo[1702]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:04 motivo sudo[1704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 16 20:17:04 motivo sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:04 motivo volumio[873]: info: MCU Signalled Playback Inactive Feb 16 20:17:04 motivo systemd[1]: Started RAAT DAEMON. Feb 16 20:17:04 motivo volumio[873]: 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: 4 Feb 16 20:17:04 motivo sudo[1704]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:04 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:04 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:04 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 20:17:04 motivo volumio[873]: info: Not Reporting Auto name since its the default one Feb 16 20:17:04 motivo volumio[873]: info: Raat Daemon started successfully Feb 16 20:17:04 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 16 20:17:04 motivo sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 16 20:17:04 motivo sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:04 motivo volumio[873]: info: TidalConnect service stoped! Feb 16 20:17:04 motivo sudo[1718]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:04 motivo sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 16 20:17:04 motivo sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:04 motivo systemd[1]: Stopping RAAT DAEMON... Feb 16 20:17:04 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 16 20:17:04 motivo systemd[1]: raat-daemon.service: Succeeded. Feb 16 20:17:04 motivo systemd[1]: Stopped RAAT DAEMON. Feb 16 20:17:04 motivo volumio[873]: info: Initializing I2S Bus Feb 16 20:17:04 motivo volumio[873]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 16 20:17:04 motivo volumio[873]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 16 20:17:04 motivo systemd[1]: Started RAAT DAEMON. Feb 16 20:17:04 motivo sudo[1721]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:04 motivo volumio[873]: info: Raat Daemon started successfully Feb 16 20:17:04 motivo mpd[1690]: Feb 16 20:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 16 20:17:04 motivo sudo[1736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 16 20:17:04 motivo systemd[1]: Started Music Player Daemon. Feb 16 20:17:04 motivo sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:04 motivo sudo[1648]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:04 motivo sudo[1669]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:04 motivo volumio[873]: error: updateQueue error: null Feb 16 20:17:04 motivo volumio[873]: error: Failed to initialize I2S BUS with silence: Error: Command failed: /usr/bin/aplay -D plughw:5 /volumio/app/silence.wav Feb 16 20:17:04 motivo volumio[873]: aplay: main:828: audio open error: Device or resource busy Feb 16 20:17:04 motivo volumio[873]: info: Successfully initialized Motivo I2S Bus Feb 16 20:17:04 motivo systemd[1]: Started Volumio Tidal Connect Service. Feb 16 20:17:04 motivo volumio[873]: error: updateQueue error: null Feb 16 20:17:04 motivo sudo[1736]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:05 motivo volumio[873]: info: Executing endpoint tc_getconfig Feb 16 20:17:05 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 16 20:17:05 motivo vtcs[1742]: STARTING TidalConnect services, version: 1.4.0.34 Feb 16 20:17:05 motivo vtcs[1742]: STARTED TidalConnect services. Feb 16 20:17:05 motivo volumio[873]: info: Executing endpoint tc_connect Feb 16 20:17:05 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 16 20:17:05 motivo volumio[873]: info: Connecting to TidalConnect Feb 16 20:17:05 motivo volumio[873]: info: CoreCommandRouter::servicePushState Feb 16 20:17:05 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:05 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:05 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:05 motivo volumio[873]: info: MRS: Pushing multiroomSync output update for this device Feb 16 20:17:05 motivo volumio[873]: info: MRS: Pushing multiroomSync output Feb 16 20:17:05 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:05 motivo volumio[873]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Feb 16 20:17:05 motivo volumio[873]: info: CoreCommandRouter::servicePushState Feb 16 20:17:05 motivo volumio[873]: info: CoreStateMachine::pushState Feb 16 20:17:05 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:05 motivo volumio[873]: info: CoreCommandRouter::volumioPushState Feb 16 20:17:05 motivo volumio[873]: info: MRS: Pushing multiroomSync output update for this device Feb 16 20:17:05 motivo volumio[873]: info: MRS: Pushing multiroomSync output Feb 16 20:17:05 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:05 motivo volumio[873]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Feb 16 20:17:05 motivo volumio[873]: info: Executing endpoint restartRAATSocket Feb 16 20:17:05 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 16 20:17:05 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:05 motivo volumio[873]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 16 20:17:06 motivo volumio[873]: info: Reporting MCU Network Status: 1 Feb 16 20:17:06 motivo volumio[873]: info: Volumio Network Manager: Network status updated: 1 Feb 16 20:17:06 motivo volumio[873]: info: MRS: Getting audio outputs on start Feb 16 20:17:06 motivo volumio[873]: info: MRS: Requesting all other devices output Feb 16 20:17:06 motivo systemd[1]: systemd-fsckd.service: Succeeded. Feb 16 20:17:07 motivo volumio[873]: info: RAAT: Requesting Headphone Status Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 16 20:17:07 motivo volumio[873]: info: MCU Signalled Headphone Mode Disabled Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 16 20:17:07 motivo volumio[873]: info: Reporting Headphone State: false Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:07 motivo volumio[873]: info: Updating RAAT Signal Path Feb 16 20:17:07 motivo volumio[873]: 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: 6 Feb 16 20:17:07 motivo volumio[873]: info: RAAT: Requesting Headphone Status Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 20:17:07 motivo volumio[873]: info: MCU Signalled Headphone Mode Disabled Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 16 20:17:07 motivo volumio[873]: info: Reporting Headphone State: false Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:07 motivo volumio[873]: info: Updating RAAT Signal Path Feb 16 20:17:07 motivo volumio[873]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:07 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 20:17:07 motivo volumio[873]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 16 20:17:07 motivo volumio[873]: info: Received Get System Info Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:07 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:07 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:07 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:07 motivo volumio[873]: info: Listing playlists Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 16 20:17:07 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 20:17:07 motivo volumio[873]: info: TidalConnect service started! Feb 16 20:17:07 motivo volumio[873]: info: Completed starting Core Plugins Feb 16 20:17:07 motivo volumio[873]: info: ------------------------------------------- Feb 16 20:17:07 motivo volumio[873]: info: ----- MyVolumio plugins startup ---- Feb 16 20:17:07 motivo volumio[873]: info: ------------------------------------------- Feb 16 20:17:07 motivo volumio[873]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 16 20:17:08 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 20:17:08 motivo volumio[873]: info: Received Get System Info Feb 16 20:17:08 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:08 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:08 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:08 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:08 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:08 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:08 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:08 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:09 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 16 20:17:10 motivo systemd[1]: systemd-hostnamed.service: Succeeded. Feb 16 20:17:10 motivo volumio[873]: info: Executing endpoint metavolumio Feb 16 20:17:10 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 20:17:10 motivo volumio[873]: info: Executing endpoint metavolumio Feb 16 20:17:10 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 20:17:10 motivo volumio[873]: info: Executing endpoint metavolumio Feb 16 20:17:10 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 20:17:11 motivo volumio[873]: info: Checking for updated MCU Firmware Feb 16 20:17:11 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 20:17:11 motivo volumio[873]: info: Firware on device is on latest version, no need to update Feb 16 20:17:13 motivo volumio[1496]: ...............................................................................................................++++ Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:15 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:15 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:15 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:15 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:15 motivo volumio[873]: verbose: New Socket.io Connection to 10.0.89.129:3000 from 10.0.89.242 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:15 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 16 20:17:15 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 16 20:17:16 motivo volumio[873]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 16 20:17:17 motivo volumio[873]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 16 20:17:17 motivo volumio[873]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 16 20:17:17 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:17 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:17 motivo volumio[873]: info: Starting MyVolumio Remote Streaming Endpoints Feb 16 20:17:17 motivo volumio[873]: info: MyVolumio not started Feb 16 20:17:17 motivo volumio[873]: info: Initializing device activation check Feb 16 20:17:17 motivo volumio[873]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 16 20:17:17 motivo volumio[873]: 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' Feb 16 20:17:17 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 16 20:17:24 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 20:17:24 motivo volumio[873]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 16 20:17:24 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 16 20:17:24 motivo volumio[873]: info: Received Get System Version Feb 16 20:17:24 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 20:17:24 motivo volumio[873]: info: Received Get System Info Feb 16 20:17:24 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:24 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:24 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:24 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:24 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:24 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:25 motivo sudo[1815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 20:17:25 motivo sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:25 motivo sudo[1815]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:25 motivo sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 20:17:25 motivo sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:25 motivo volumio[1496]: .......................................................................................................................................++++ Feb 16 20:17:25 motivo sudo[1817]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:25 motivo volumio[1496]: e is 65537 (0x010001) Feb 16 20:17:25 motivo volumio[1496]: writing RSA key Feb 16 20:17:25 motivo volumio[873]: verbose: New Socket.io Connection to 10.0.89.129 from 10.0.89.242 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:25 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 20:17:25 motivo volumio[873]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 16 20:17:25 motivo volumio[873]: info: Received Get System Info Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:25 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:25 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:25 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:25 motivo volumio[873]: info: Listing playlists Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 20:17:25 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 16 20:17:26 motivo sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 16 20:17:26 motivo sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:26 motivo sudo[1844]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:26 motivo sudo[1852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 16 20:17:26 motivo sudo[1852]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:26 motivo sudo[1852]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:26 motivo volumio[873]: verbose: New Socket.io Connection to 10.0.89.129 from 10.0.89.242 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Feb 16 20:17:26 motivo volumio[873]: info: Executing endpoint metavolumio Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 20:17:26 motivo volumio[873]: info: Executing endpoint metavolumio Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::volumioGetVisibleSources Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:26 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::volumioGetQueue Feb 16 20:17:26 motivo volumio[873]: info: CoreStateMachine::getQueue Feb 16 20:17:26 motivo volumio[873]: info: CorePlayQueue::getQueue Feb 16 20:17:26 motivo volumio[873]: info: Listing playlists Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 16 20:17:26 motivo volumio[873]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 16 20:17:26 motivo volumio[873]: info: Received Get System Info Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:26 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:26 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:26 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 20:17:26 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 16 20:17:27 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 16 20:17:27 motivo volumio[873]: error: MyVolumio Plugin failed to start in a timely fashion Feb 16 20:17:27 motivo volumio[873]: [Metrics] CommandRouter: 44s 248.61ms Feb 16 20:17:27 motivo volumio[873]: info: CoreCommandRouter::volumiosetStartupVolume Feb 16 20:17:27 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:27 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:28 motivo volumio[873]: info: CoreCommandRouter::Close All Modals sent Feb 16 20:17:28 motivo volumio[873]: info: CoreCommandRouter::Close All Modals sent Feb 16 20:17:28 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 16 20:17:28 motivo volumio[873]: info: Received Get System Info Feb 16 20:17:28 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:28 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:28 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:28 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:28 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:28 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:29 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 16 20:17:29 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 16 20:17:29 motivo systemd[1]: systemd-timedated.service: Succeeded. Feb 16 20:17:29 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 16 20:17:31 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 20:17:31 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 16 20:17:31 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 16 20:17:31 motivo volumio-remote-updater[714]: No test mode Feb 16 20:17:31 motivo volumio-remote-updater[714]: No alpha test mode Feb 16 20:17:31 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 16 20:17:32 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 20:17:32 motivo volumio[873]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 16 20:17:32 motivo volumio[873]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 16 20:17:32 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 16 20:17:32 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:32 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:35 motivo volumio[873]: info: BOOT COMPLETED Feb 16 20:17:35 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:35 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:35 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 16 20:17:35 motivo volumio[873]: info: Not Reporting Auto name since its the default one Feb 16 20:17:35 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 16 20:17:35 motivo sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 16 20:17:35 motivo sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:35 motivo sudo[1874]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:35 motivo sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 16 20:17:35 motivo sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 20:17:35 motivo systemd[1]: Stopping RAAT DAEMON... Feb 16 20:17:35 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 16 20:17:35 motivo systemd[1]: raat-daemon.service: Succeeded. Feb 16 20:17:35 motivo systemd[1]: Stopped RAAT DAEMON. Feb 16 20:17:35 motivo systemd[1]: Started RAAT DAEMON. Feb 16 20:17:35 motivo sudo[1876]: pam_unix(sudo:session): session closed for user root Feb 16 20:17:35 motivo volumio[873]: info: Raat Daemon started successfully Feb 16 20:17:36 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 16 20:17:36 motivo volumio[873]: info: Executing endpoint restartRAATSocket Feb 16 20:17:36 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 16 20:17:36 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:36 motivo volumio[873]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 16 20:17:37 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:37 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:37 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 16 20:17:37 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 16 20:17:37 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 16 20:17:37 motivo volumio[873]: info: CoreCommandRouter::volumioGetBrowseSources Feb 16 20:17:37 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 20:17:38 motivo volumio[873]: info: RAAT: Requesting Headphone Status Feb 16 20:17:38 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 16 20:17:38 motivo volumio[873]: info: MCU Signalled Headphone Mode Disabled Feb 16 20:17:38 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 16 20:17:38 motivo volumio[873]: info: Reporting Headphone State: false Feb 16 20:17:38 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:38 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 16 20:17:38 motivo volumio[873]: info: Updating RAAT Signal Path Feb 16 20:17:40 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:40 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:40 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:40 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:40 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:40 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:43 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:17:43 motivo volumio[873]: info: Preload queue cleared Feb 16 20:17:44 motivo ntpd[837]: Soliciting pool server 206.108.0.131 Feb 16 20:17:44 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 16 20:17:44 motivo volumio[873]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 16 20:17:44 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 16 20:17:44 motivo volumio[873]: info: Received Get System Version Feb 16 20:17:44 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 16 20:17:44 motivo volumio[873]: info: Received Get System Info Feb 16 20:17:44 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:17:44 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:17:44 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:17:44 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:17:44 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:17:44 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:17:45 motivo ntpd[837]: Soliciting pool server 142.4.192.253 Feb 16 20:17:45 motivo ntpd[837]: Soliciting pool server 23.133.168.244 Feb 16 20:17:45 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:17:46 motivo volumio[873]: info: Preload queue cleared Feb 16 20:17:46 motivo ntpd[837]: Soliciting pool server 144.217.4.129 Feb 16 20:17:47 motivo ntpd[837]: Soliciting pool server 174.138.207.42 Feb 16 20:17:47 motivo ntpd[837]: Soliciting pool server 54.39.17.239 Feb 16 20:17:48 motivo ntpd[837]: Soliciting pool server 216.232.132.95 Feb 16 20:17:49 motivo ntpd[837]: Soliciting pool server 217.180.209.214 Feb 16 20:17:49 motivo ntpd[837]: Soliciting pool server 162.159.200.123 Feb 16 20:17:50 motivo ntpd[837]: Soliciting pool server 149.56.19.163 Feb 16 20:17:50 motivo ntpd[837]: Soliciting pool server 216.232.132.102 Feb 16 20:17:51 motivo ntpd[837]: Soliciting pool server 192.95.0.223 Feb 16 20:17:51 motivo ntpd[837]: Soliciting pool server 2606:4700:f1::123 Feb 16 20:17:52 motivo ntpd[837]: Soliciting pool server 148.113.192.80 Feb 16 20:17:54 motivo ntpd[837]: receive: Unexpected origin timestamp 0xeb5cc671.a0641d20 does not match aorg 0000000000.00000000 from server@148.113.192.80 xmt 0xeb5cc672.755e7fc2 Feb 16 20:17:54 motivo ntpd[837]: receive: Unexpected origin timestamp 0xeb5cc671.a0734afe does not match aorg 0000000000.00000000 from server@142.4.192.253 xmt 0xeb5cc672.7757995e Feb 16 20:17:54 motivo ntpd[837]: receive: Unexpected origin timestamp 0xeb5cc671.a06ca3cb does not match aorg 0000000000.00000000 from server@54.39.17.239 xmt 0xeb5cc672.771d2002 Feb 16 20:17:54 motivo ntpd[837]: receive: Unexpected origin timestamp 0xeb5cc671.a06ee423 does not match aorg 0000000000.00000000 from server@174.138.207.42 xmt 0xeb5cc672.76fd65c8 Feb 16 20:17:54 motivo ntpd[837]: receive: Unexpected origin timestamp 0xeb5cc671.a0759e6f does not match aorg 0000000000.00000000 from server@206.108.0.131 xmt 0xeb5cc672.77e39eb0 Feb 16 20:17:54 motivo ntpd[837]: receive: Unexpected origin timestamp 0xeb5cc671.a06a1a0b does not match aorg 0000000000.00000000 from server@216.232.132.95 xmt 0xeb5cc672.7e415289 Feb 16 20:18:00 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:01 motivo volumio[873]: info: Preload queue cleared Feb 16 20:18:08 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:09 motivo volumio[873]: info: Preload queue cleared Feb 16 20:18:11 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:18:11 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:18:11 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:18:11 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:18:11 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:18:11 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:18:14 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:14 motivo volumio[873]: info: Preload queue cleared Feb 16 20:18:17 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:17 motivo volumio[873]: info: Preload queue cleared Feb 16 20:18:29 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:29 motivo volumio[873]: info: Preload queue cleared Feb 16 20:18:32 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:32 motivo volumio[873]: info: Preload queue cleared Feb 16 20:18:34 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:34 motivo volumio[873]: info: Preload queue cleared Feb 16 20:18:38 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:38 motivo volumio[873]: info: Preload queue cleared Feb 16 20:18:41 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 20:18:41 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 20:18:41 motivo volumio[873]: info: Discovery: Getting this device information Feb 16 20:18:41 motivo volumio[873]: info: CoreCommandRouter::volumioGetState Feb 16 20:18:41 motivo volumio[873]: info: CorePlayQueue::getTrack 0 Feb 16 20:18:41 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 20:18:43 motivo volumio[873]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 16 20:18:43 motivo volumio[873]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 20:18:43 motivo volumio[873]: TypeError: Cannot read property 'length' of undefined Feb 16 20:18:43 motivo volumio[873]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Feb 16 20:18:43 motivo volumio[873]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Feb 16 20:18:43 motivo volumio[873]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Feb 16 20:18:43 motivo volumio[873]: at Parser.emit (events.js:315:20) Feb 16 20:18:43 motivo volumio[873]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Feb 16 20:18:43 motivo volumio[873]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Feb 16 20:18:43 motivo volumio[873]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Feb 16 20:18:43 motivo volumio[873]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Feb 16 20:18:43 motivo volumio[873]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Feb 16 20:18:43 motivo volumio[873]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Feb 16 20:18:43 motivo volumio[873]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Feb 16 20:18:43 motivo volumio[873]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Feb 16 20:18:43 motivo volumio[873]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Feb 16 20:18:43 motivo volumio[873]: at IncomingMessage.emit (events.js:327:22) Feb 16 20:18:43 motivo volumio[873]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 16 20:18:43 motivo volumio[873]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 16 20:18:43 motivo volumio[873]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 20:18:43 motivo sudo[2178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 20:17 Feb 16 20:18:43 motivo sudo[2178]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:02:37 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="f3429790fcb2ce685253f61650bd5f66"