-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2025-11-04 20:26:09 UTC. -- Nov 04 20:25:00 motivo volumio[1066]: Starting albumart workers Nov 04 20:25:00 motivo volumio[1066]: Starting albumart workers Nov 04 20:25:00 motivo volumio[1066]: Starting albumart workers Nov 04 20:25:01 motivo volumio[1066]: info: Applying required configuration parameters for plugin hi_res_audio Nov 04 20:25:01 motivo volumio[1066]: info: Loading plugin "inputs"... Nov 04 20:25:02 motivo volumio[1066]: info: Loading plugin "qobuz"... Nov 04 20:25:03 motivo volumio[1066]: info: Loading plugin "smart_inputs"... Nov 04 20:25:03 motivo volumio[1066]: info: Loading plugin "tidal"... Nov 04 20:25:04 motivo volumio[1066]: info: Loading plugin "motivocontrol"... Nov 04 20:25:04 motivo volumio[1066]: info: Initializing System Ready GPIO for kernel version: 6.6.30-v8+ Nov 04 20:25:04 motivo volumio[1066]: info: Adding this device properties Nov 04 20:25:04 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Nov 04 20:25:04 motivo volumio[1066]: info: Setting Additional Device Volumio Properties: [object Object] Nov 04 20:25:04 motivo volumio[1066]: info: Checking For Motivo Hardware Nov 04 20:25:04 motivo volumio[1066]: info: Loading plugin "updater_comm"... Nov 04 20:25:04 motivo volumio[1066]: info: Plugin mpdemulation is not enabled Nov 04 20:25:04 motivo volumio[1066]: info: Loading plugin "rest_api"... Nov 04 20:25:04 motivo volumio[1066]: info: Loading plugin "websocket"... Nov 04 20:25:04 motivo volumio[1066]: info: Starting Socket.io Server version 2.3.0 Nov 04 20:25:04 motivo volumio[1066]: info: Loading i18n strings for locale en Nov 04 20:25:04 motivo volumio[1066]: Updating browse sources language Nov 04 20:25:04 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::initPlayerControls Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: Express server listening on port 3000 Nov 04 20:25:05 motivo volumio[1066]: [Metrics] WebUI: 17s 483.96ms Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::resetVolumioState Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::getcurrentVolume Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::volumioRetrievevolume Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:05 motivo sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 04 20:25:05 motivo sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo sudo[1617]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:05 motivo sudo[1619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 04 20:25:05 motivo sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo sudo[1619]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:05 motivo volumio[1066]: info: Volumio Network Manager: Network status updated: 2 Nov 04 20:25:05 motivo volumio[1066]: info: Motivo Hardware found Nov 04 20:25:05 motivo volumio[1066]: info: MRS: Removed streaming files Nov 04 20:25:05 motivo volumio[1066]: info: MRS: volumioStreaming STOPPED Nov 04 20:25:05 motivo volumio[1066]: info: MRS: SNAPSERVER STOPPED Nov 04 20:25:05 motivo volumio[1066]: info: MRS: SNAPCLIENT STOPPED Nov 04 20:25:05 motivo volumio[1066]: info: VolumeController:: Volume=46 Mute =false Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:05 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::updateTrackBlock Nov 04 20:25:05 motivo volumio[1066]: info: CorePlayQueue::getTrackBlock Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::volumioRetrievevolume Nov 04 20:25:05 motivo volumio[1066]: info: Reloading queue from file Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::setRepeat null single undefined Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:05 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::setRandom null Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:05 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:05 motivo volumio[1066]: info: Setting Device type: Raspberry PI Nov 04 20:25:05 motivo volumio[1066]: info: Completed loading Core Plugins Nov 04 20:25:05 motivo volumio[1066]: info: Preparing to generate the ALSA configuration file Nov 04 20:25:05 motivo sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 04 20:25:05 motivo sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo volumio[1066]: info: VolumeController:: Volume=46 Mute =false Nov 04 20:25:05 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:05 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:05 motivo volumio[1066]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 04 20:25:05 motivo volumio[1066]: info: Reading ALSA contributions from plugins. Nov 04 20:25:05 motivo volumio[1066]: info: Asound.conf file unchanged, so no further update is needed Nov 04 20:25:05 motivo volumio[1066]: info: Output device has changed, restarting MPD Nov 04 20:25:05 motivo systemd[1]: Started UPnP Renderer front-end to MPD. Nov 04 20:25:05 motivo volumio[1066]: info: Output device has changed, restarting Shairport Sync Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo sudo[1633]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:05 motivo sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 04 20:25:05 motivo sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 04 20:25:05 motivo sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo sudo[1637]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:05 motivo systemd[1]: Stopping Music Player Daemon... Nov 04 20:25:05 motivo volumio[1066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 04 20:25:05 motivo volumio[1066]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: ___________ START PLUGINS ___________ Nov 04 20:25:05 motivo volumio[1066]: info: ControllerMpd::onStart: Initializing MPD Nov 04 20:25:05 motivo volumio[1066]: info: Creating MPD Configuration file Nov 04 20:25:05 motivo systemd[1]: mpd.service: Succeeded. Nov 04 20:25:05 motivo systemd[1]: Stopped Music Player Daemon. Nov 04 20:25:05 motivo sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 04 20:25:05 motivo sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo systemd[1]: Starting Music Player Daemon... Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 04 20:25:05 motivo volumio[1066]: info: [1762287905791] CoreMusicLibrary::Adding element Media Servers Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 20:25:05 motivo sudo[1648]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:05 motivo sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 04 20:25:05 motivo sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 04 20:25:05 motivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 04 20:25:05 motivo systemd[1]: mpd.service: Succeeded. Nov 04 20:25:05 motivo systemd[1]: Stopped Music Player Daemon. Nov 04 20:25:05 motivo systemd[1]: Starting Music Player Daemon... Nov 04 20:25:05 motivo volumio[1066]: info: Adding METAVOLUMIO REST API Endpoints Nov 04 20:25:05 motivo volumio[1066]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 04 20:25:05 motivo volumio[1066]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 04 20:25:05 motivo volumio[1066]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 04 20:25:05 motivo volumio[1066]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:05 motivo sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Motivo Nov 04 20:25:05 motivo sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo sudo[1656]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:05 motivo volumio[1066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:05 motivo sudo[1655]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 04 20:25:05 motivo sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:05 motivo sudo[1655]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:05 motivo volumio[1066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 04 20:25:05 motivo volumio[1066]: info: Preparing CD Folders Nov 04 20:25:05 motivo volumio[1066]: info: Adding CD REST API Endpoints Nov 04 20:25:05 motivo volumio[1066]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 04 20:25:05 motivo volumio[1066]: info: Starting UDEV Watcher for CD Nov 04 20:25:05 motivo volumio[1066]: info: Detecting CD presence with UDEV Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 04 20:25:05 motivo volumio[1066]: info: [1762287905974] CoreMusicLibrary::Adding element Last_100 Nov 04 20:25:05 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 20:25:05 motivo volumio[1066]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Nov 04 20:25:06 motivo volumio[1066]: info: QobuzConnect: Starting Qobuz Connect socket and service Nov 04 20:25:06 motivo volumio[1066]: info: Starting RAAT Plugin Nov 04 20:25:06 motivo sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 04 20:25:06 motivo sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Nov 04 20:25:06 motivo volumio[1066]: info: Additional UI Settings Added for plugin music_service/raat Nov 04 20:25:06 motivo volumio[1066]: info: Registering DSP Elements listener and retrieving current ones Nov 04 20:25:06 motivo volumio[1066]: info: Additional DSP elements updated Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:06 motivo volumio[1066]: info: Updating RAAT Signal Path Nov 04 20:25:06 motivo volumio[1066]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Nov 04 20:25:06 motivo volumio[1066]: info: Streaming services startup Nov 04 20:25:06 motivo sudo[1670]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:06 motivo volumio[1066]: info: Starting Streaming Daemon Nov 04 20:25:06 motivo sudo[1674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 04 20:25:06 motivo sudo[1674]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:06 motivo sudo[1676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 04 20:25:06 motivo sudo[1676]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 04 20:25:06 motivo volumio[1066]: info: [1762287906172] CoreMusicLibrary::Adding element Webradio Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 20:25:06 motivo systemd[1]: Started Volumio Qobuz Connect Service. Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 20:25:06 motivo sudo[1674]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:06 motivo volumio[1066]: info: Initializing BBC Radios Nov 04 20:25:06 motivo sudo[1676]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:06 motivo sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 04 20:25:06 motivo sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:06 motivo sudo[1685]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 04 20:25:06 motivo volumio[1066]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:06 motivo volumio[1066]: error: Hi Res Audio Failed Login: Missing Login Data Nov 04 20:25:06 motivo volumio[1066]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 04 20:25:06 motivo volumio[1066]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 04 20:25:06 motivo volumio[1066]: info: Initializing Serial Communication on port /dev/ttyAMA4 Nov 04 20:25:06 motivo volumio[1066]: info: Touch Event Listener Process Starting Nov 04 20:25:06 motivo kernel: uart-pl011 fe201800.serial: no DMA platform data Nov 04 20:25:06 motivo volumio[1066]: info: Adding inputs REST Endpoints Nov 04 20:25:06 motivo volumio[1066]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 04 20:25:06 motivo volumio[1066]: info: Scanning Audio Inputs Nov 04 20:25:06 motivo sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Nov 04 20:25:06 motivo sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:06 motivo volumio[1066]: info: Checking against Known Cards name Nov 04 20:25:06 motivo volumio[1066]: info: Checking against Known Cards name Nov 04 20:25:06 motivo volumio[1066]: info: Adding Server instance for streaming Nov 04 20:25:06 motivo volumio[1066]: info: Volumio Calling Home Nov 04 20:25:06 motivo volumio[1066]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Nov 04 20:25:06 motivo volumio[1066]: (node:1066) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Nov 04 20:25:06 motivo volumio[1066]: (Use `node --trace-deprecation ...` to show where the warning was created) Nov 04 20:25:06 motivo volumio[1066]: info: Stopping AccessToken refresher cron for QOBUZ Nov 04 20:25:06 motivo volumio[1066]: info: AccessToken refresher cron started for QOBUZ Nov 04 20:25:06 motivo volumio[1066]: info: Stopping AccessToken refresher cron Nov 04 20:25:06 motivo volumio[1066]: info: AccessToken refresher cron started Nov 04 20:25:06 motivo volumio[1066]: info: Adding TIDAL REST API Endpoints Nov 04 20:25:06 motivo volumio[1066]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 04 20:25:06 motivo volumio[1066]: info: Adding QOBUZ REST API Endpoints Nov 04 20:25:06 motivo volumio[1066]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 04 20:25:06 motivo volumio[1066]: info: Discovery: adding 058b4efa-6821-4c2e-8d9d-2d08a130c405 Nov 04 20:25:06 motivo volumio[1066]: info: Discovery: Found device Motivo Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:06 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:06 motivo volumio[1066]: info: MRS: Pushing multiroomSync output for this device Nov 04 20:25:06 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:06 motivo volumio[1066]: info: Adding audio output: Nov 04 20:25:06 motivo volumio[1066]: info: Adding audio output: Nov 04 20:25:06 motivo volumio[1066]: info: Serial port opened successfully Nov 04 20:25:06 motivo volumio[1066]: info: Sending serial start messages Nov 04 20:25:06 motivo volumio[1066]: info: Display with brightness control is connected, setting display brightness Nov 04 20:25:06 motivo volumio[1066]: info: Setting Display Brightness to 12 Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:06 motivo volumio[1066]: info: Reporting MCU Network Status: 2 Nov 04 20:25:06 motivo volumio[1066]: error: Cannot start Volumio Streaming Daemon Nov 04 20:25:06 motivo volumio[1066]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 04 20:25:06 motivo volumio[1066]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 04 20:25:06 motivo volumio[1066]: info: RAAT Albumart path created successfully Nov 04 20:25:06 motivo volumio[1066]: info: Bluetooth name changed to Motivo Nov 04 20:25:06 motivo volumio[1066]: info: MPD Permissions set Nov 04 20:25:06 motivo volumio[1066]: info: MPD Permissions set Nov 04 20:25:06 motivo volumio[1066]: info: Upmpdcli Daemon Started Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Nov 04 20:25:06 motivo volumio[1066]: info: Setting Device Volume Override Nov 04 20:25:06 motivo mpd[1665]: Nov 04 20:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 04 20:25:06 motivo systemd[1]: Started Music Player Daemon. Nov 04 20:25:06 motivo sudo[1651]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:06 motivo sudo[1639]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:06 motivo volumio[1066]: info: Applying Volume Override Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 04 20:25:06 motivo volumio[1066]: info: Updating Volume Controller Parameters: Device: 5 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 04 20:25:06 motivo volumio[1066]: info: Enabling external Volume Control Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:06 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:06 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:06 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:06 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:06 motivo volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Nov 04 20:25:06 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:07 motivo volumio[1066]: info: Executing endpoint qc_getconfig Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.079 [1684.1684] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Nov 04 20:25:07 motivo volumio[1066]: info: Setting Additional System Software info: Hardware Revision: 2.2 Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Nov 04 20:25:07 motivo volumio[1066]: info: Setting HW Firmware info: undefined Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Nov 04 20:25:07 motivo volumio[1066]: info: Setting HW Version info: 2.2 Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Nov 04 20:25:07 motivo volumio[1066]: info: Setting Additional System Software info: Hardware Revision: 2.2, Firmware Version: 0.1.9 Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Nov 04 20:25:07 motivo volumio[1066]: info: Setting HW Firmware info: 0.1.9 Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Nov 04 20:25:07 motivo volumio[1066]: info: Setting HW Version info: 2.2 Nov 04 20:25:07 motivo volumio[1066]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 04 20:25:07 motivo volumio[1066]: info: MCU Signalled Headphone Mode Disabled Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Nov 04 20:25:07 motivo volumio[1066]: info: Reporting Headphone State: false Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: Updating RAAT Signal Path Nov 04 20:25:07 motivo volumio[1066]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Nov 04 20:25:07 motivo volumio[1066]: info: MCU Signalled Sleep Mode Disabled Nov 04 20:25:07 motivo volumio[1066]: info: Enabling Advanced system settings configuration Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Nov 04 20:25:07 motivo volumio[1066]: info: Additional UI Settings Added for plugin music_service/inputs Nov 04 20:25:07 motivo volumio[1066]: info: MCU Signalled Auto Boot Mode On Power Disabled Nov 04 20:25:07 motivo sudo[1726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Nov 04 20:25:07 motivo sudo[1726]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:07 motivo sudo[1726]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.166 [1684.1684] INFO VolumeManager: [0x24ec280]: Setting new playback volume: 75 Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.167 [1684.1684] INFO VolumeManager: [0x24ec280]: Setting new mute state: 0 Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.167 [1684.1684] INFO QobuzConnect: [0x24ecc50]: Client initialized! Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.167 [1684.1684] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.177 [1684.1684] INFO LocalConfigManager: [0x24ebb60]: Starting Local Configuration server Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.177 [1684.1684] INFO SampleApp: Starting Local configuration server Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.178 [1684.1684] INFO SampleApp: Connected to UNIX socket client 0x24e1f60 Nov 04 20:25:07 motivo volumio[1066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Nov 04 20:25:07 motivo volumio[1066]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 04 20:25:07 motivo volumio[1066]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 04 20:25:07 motivo qobuz-connect[1684]: 20251104 20:25:07.228 [1684.1684] INFO SampleApp: Playback volume changed: 75 Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:07 motivo volumio[1066]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Nov 04 20:25:07 motivo volumio[1066]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Nov 04 20:25:07 motivo volumio[1066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:07 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:07 motivo volumio[1066]: info: Volumio called home Nov 04 20:25:07 motivo sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Nov 04 20:25:07 motivo sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:07 motivo sudo[1741]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:07 motivo volumio[1066]: error: MPD error: The expression evaluated to a falsy value: Nov 04 20:25:07 motivo volumio[1066]: assert.ok(self.idling) Nov 04 20:25:07 motivo volumio[1066]: error: The expression evaluated to a falsy value: Nov 04 20:25:07 motivo volumio[1066]: assert.ok(self.idling) Nov 04 20:25:07 motivo volumio[1066]: info: MPD running with PID1665 Nov 04 20:25:07 motivo volumio[1066]: ,establishing connection Nov 04 20:25:07 motivo volumio[1066]: error: updateQueue error: null Nov 04 20:25:07 motivo volumio[1066]: info: Starting Shairport Sync Nov 04 20:25:07 motivo volumio[1066]: info: Starting Shairport Sync Nov 04 20:25:07 motivo volumio[1066]: info: Starting Shairport Sync Nov 04 20:25:07 motivo sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 04 20:25:07 motivo sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:07 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 04 20:25:07 motivo sudo[1746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 04 20:25:07 motivo sudo[1746]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:07 motivo sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 04 20:25:07 motivo sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:07 motivo volumio[1066]: info: Adding Inputs via Serial API Nov 04 20:25:07 motivo systemd[1]: shairport-sync.service: Succeeded. Nov 04 20:25:07 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 04 20:25:07 motivo volumio[1066]: info: Adding Advanced Audio Settings via Serial API Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Nov 04 20:25:07 motivo volumio[1066]: info: Additional UI Settings Added for plugin music_service/inputs Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:07 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:07 motivo volumio[1066]: info: Adding Advanced Audio Settings via Serial API Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Nov 04 20:25:07 motivo volumio[1066]: error: updateQueue error: null Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:07 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:07 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 04 20:25:07 motivo sudo[1744]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:07 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 04 20:25:07 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 04 20:25:07 motivo systemd[1]: shairport-sync.service: Succeeded. Nov 04 20:25:07 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 04 20:25:07 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:07 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:07 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:07 motivo volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Nov 04 20:25:07 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 04 20:25:07 motivo sudo[1749]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:07 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:07 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:07 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:07 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:07 motivo volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Nov 04 20:25:07 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:07 motivo sudo[1746]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:07 motivo volumio[1635]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 04 20:25:07 motivo volumio[1066]: info: Shairport-Sync Started Nov 04 20:25:07 motivo volumio[1066]: Error adding Membership: Error: addMembership EINVAL Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::servicePushState Nov 04 20:25:07 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:07 motivo volumio[1066]: 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 04 20:25:07 motivo volumio[1066]: verbose: CURRENT POSITION 0 Nov 04 20:25:07 motivo volumio[1066]: info: CoreStateMachine::syncState stateService play Nov 04 20:25:07 motivo volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::volumiosetSourceActiveno-source Nov 04 20:25:07 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 20:25:07 motivo volumio[1066]: info: Shairport-Sync Started Nov 04 20:25:07 motivo volumio[1066]: info: Shairport-Sync Started Nov 04 20:25:07 motivo volumio[1066]: info: MCU Signalled Playback Inactive Nov 04 20:25:08 motivo volumio[1066]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 3 Nov 04 20:25:08 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 20:25:08 motivo sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 04 20:25:08 motivo sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:08 motivo volumio-remote-updater[737]: [2025-11-04 20:25:08] [connect] Successful connection Nov 04 20:25:08 motivo systemd[1]: Started Volumio Bluetooth Module. Nov 04 20:25:08 motivo sudo[1773]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:09 motivo sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 04 20:25:09 motivo sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:09 motivo volumiobt[1775]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 04 20:25:09 motivo volumio-remote-updater[737]: [2025-11-04 20:25:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762287908 101 Nov 04 20:25:09 motivo sudo[1776]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:09 motivo volumio[1066]: 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: 4 Nov 04 20:25:09 motivo volumio[1066]: info: Volumio BT Module successfully started Nov 04 20:25:09 motivo volumio[1066]: info: TidalConnect service stoped! Nov 04 20:25:09 motivo volumio[1066]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 04 20:25:09 motivo volumio[1066]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 04 20:25:09 motivo sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 04 20:25:09 motivo sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:09 motivo systemd[1]: Started Volumio Tidal Connect Service. Nov 04 20:25:09 motivo sudo[1788]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:09 motivo volumio[1066]: info: Initializing I2S Bus Nov 04 20:25:09 motivo volumio[1066]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 5 Nov 04 20:25:09 motivo volumio[1066]: info: Executing endpoint tc_getconfig Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 04 20:25:09 motivo vtcs[1790]: STARTING TidalConnect services, version: 1.5.1-beta1 Nov 04 20:25:09 motivo vtcs[1790]: STARTED TidalConnect services. Nov 04 20:25:09 motivo volumio[1066]: info: Executing endpoint tc_connect Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 04 20:25:09 motivo volumio[1066]: info: Connecting to TidalConnect Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::servicePushState Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 04 20:25:09 motivo volumio[1066]: verbose: CURRENT POSITION 0 Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::syncState stateService stop Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::syncState currentStatus play Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::stPlaybackTimer Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::servicePushState Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 04 20:25:09 motivo volumio[1066]: verbose: CURRENT POSITION 0 Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::syncState stateService stop Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:09 motivo volumio[1066]: info: No code Nov 04 20:25:09 motivo volumio[1066]: info: CoreStateMachine::pushState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioPushState Nov 04 20:25:09 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:09 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Nov 04 20:25:09 motivo volumio[1066]: info: MRS: Pushing multiroomSync output Nov 04 20:25:10 motivo sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 04 20:25:10 motivo sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:10 motivo volumiobt[1775]: pulseaudio: no process found Nov 04 20:25:10 motivo sudo[1812]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:10 motivo dbus-daemon[726]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.25' (uid=1000 pid=1816 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 04 20:25:10 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 20:25:10 motivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 04 20:25:10 motivo volumio[1066]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 20:25:10 motivo dbus-daemon[726]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 04 20:25:10 motivo rtkit-daemon[1817]: Successfully called chroot. Nov 04 20:25:10 motivo rtkit-daemon[1817]: Successfully dropped privileges. Nov 04 20:25:10 motivo rtkit-daemon[1817]: Successfully limited resources. Nov 04 20:25:10 motivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 04 20:25:10 motivo rtkit-daemon[1817]: Canary thread running. Nov 04 20:25:10 motivo rtkit-daemon[1817]: Running. Nov 04 20:25:10 motivo rtkit-daemon[1817]: Watchdog thread running. Nov 04 20:25:10 motivo dbus-daemon[726]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.26' (uid=0 pid=1817 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 04 20:25:10 motivo systemd[1]: Starting Authorization Manager... Nov 04 20:25:10 motivo volumio[1066]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 04 20:25:10 motivo polkitd[1820]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 04 20:25:10 motivo dbus-daemon[726]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 04 20:25:10 motivo systemd[1]: Started Authorization Manager. Nov 04 20:25:10 motivo volumio[1635]: ............................++++ Nov 04 20:25:10 motivo pulseaudio[1816]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 04 20:25:10 motivo pulseaudio[1816]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 04 20:25:10 motivo bluetoothd[799]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Nov 04 20:25:10 motivo bluetoothd[799]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Nov 04 20:25:10 motivo pulseaudio[1816]: 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 04 20:25:10 motivo volumiobt[1775]: Applying permissions Nov 04 20:25:10 motivo volumiobt[1775]: Setting BT discoverable and pairable Nov 04 20:25:10 motivo kernel: Bluetooth: RFCOMM TTY layer initialized Nov 04 20:25:10 motivo kernel: Bluetooth: RFCOMM socket layer initialized Nov 04 20:25:10 motivo kernel: Bluetooth: RFCOMM ver 1.11 Nov 04 20:25:10 motivo volumiobt[1775]: Agent registered Nov 04 20:25:10 motivo volumiobt[1775]: [bluetooth]# -e power on Nov 04 20:25:10 motivo volumiobt[1775]: Invalid command in menu main: -e Nov 04 20:25:10 motivo volumiobt[1775]: Nov 04 20:25:10 motivo volumiobt[1775]: Use "help" for a list of available commands in a menu. Nov 04 20:25:10 motivo volumiobt[1775]: Use "menu " if you want to enter any submenu. Nov 04 20:25:10 motivo volumiobt[1775]: Use "back" if you want to return to menu main. Nov 04 20:25:10 motivo volumiobt[1775]: [bluetooth]# agent on Nov 04 20:25:10 motivo volumiobt[1775]: Agent is already registered Nov 04 20:25:10 motivo volumiobt[1775]: [bluetooth]# discoverable on Nov 04 20:25:10 motivo volumiobt[1775]: [bluetooth]# pairable on Nov 04 20:25:10 motivo volumiobt[1775]: [bluetooth]# agent NoInputNoOutput Nov 04 20:25:10 motivo volumiobt[1775]: Agent is already registered Nov 04 20:25:10 motivo volumiobt[1775]: [bluetooth]# default-agent Nov 04 20:25:10 motivo volumiobt[1775]: [bluetooth]# quit Nov 04 20:25:10 motivo volumiobt[1775]: [59B blob data] Nov 04 20:25:10 motivo volumio[1066]: info: Successfully initialized Motivo I2S Bus Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::volumioGetVisibleSources Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:11 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 04 20:25:11 motivo volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 04 20:25:11 motivo volumio[1066]: info: Received Get System Info Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 20:25:11 motivo volumio[1066]: info: Discovery: Getting this device information Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:11 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:11 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:11 motivo volumio[1066]: info: Listing playlists Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:11 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:11 motivo volumio[1066]: info: MRS: Getting audio outputs on start Nov 04 20:25:11 motivo volumio[1066]: info: MRS: Requesting all other devices output Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 20:25:11 motivo volumio[1066]: info: Received Get System Info Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 20:25:11 motivo volumio[1066]: info: Discovery: Getting this device information Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:11 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:11 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 20:25:12 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 04 20:25:12 motivo volumio[1635]: .................++++ Nov 04 20:25:12 motivo volumio[1635]: e is 65537 (0x010001) Nov 04 20:25:12 motivo volumio[1635]: writing RSA key Nov 04 20:25:12 motivo volumio[1066]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 04 20:25:12 motivo volumio[1066]: info: TidalConnect service started! Nov 04 20:25:12 motivo volumio[1066]: info: Completed starting Core Plugins Nov 04 20:25:12 motivo volumio[1066]: info: ------------------------------------------- Nov 04 20:25:12 motivo volumio[1066]: info: ----- MyVolumio plugins startup ---- Nov 04 20:25:12 motivo volumio[1066]: info: ------------------------------------------- Nov 04 20:25:12 motivo volumio[1066]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 04 20:25:12 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 04 20:25:13 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 20:25:13 motivo volumio[1066]: info: Received Get System Info Nov 04 20:25:13 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 20:25:13 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 20:25:13 motivo volumio[1066]: info: Discovery: Getting this device information Nov 04 20:25:13 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:13 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:13 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 20:25:14 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 04 20:25:16 motivo volumio[1066]: info: Checking for updated MCU Firmware Nov 04 20:25:16 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 20:25:16 motivo volumio[1066]: info: Firware on device is on latest version, no need to update Nov 04 20:25:17 motivo systemd[1]: systemd-timedated.service: Succeeded. Nov 04 20:25:19 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 04 20:25:20 motivo volumio[1066]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 04 20:25:21 motivo volumio[1066]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 04 20:25:21 motivo volumio[1066]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 04 20:25:21 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:21 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:21 motivo volumio[1066]: info: Starting MyVolumio Remote Streaming Endpoints Nov 04 20:25:21 motivo volumio[1066]: info: MyVolumio login type: Token Nov 04 20:25:21 motivo volumio[1066]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 04 20:25:21 motivo volumio[1066]: 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 04 20:25:22 motivo volumio[1066]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 04 20:25:22 motivo volumio[1066]: info: MyVolumio token set successfully Nov 04 20:25:22 motivo volumio[1066]: info: MYVOLUMIO: Adding device Nov 04 20:25:22 motivo volumio[1066]: info: MYVOLUMIO: Evaluating Server Nov 04 20:25:23 motivo volumio[1066]: info: MyVolumio Plan changed: premium Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Nov 04 20:25:23 motivo volumio[1066]: info: Removing browser output: myVolumio user plan is not superstar Nov 04 20:25:23 motivo volumio[1066]: info: Removing audio output: Nov 04 20:25:23 motivo volumio[1066]: info: MYVOLUMIO: Adding device Nov 04 20:25:23 motivo volumio[1066]: info: MYVOLUMIO: Evaluating Server Nov 04 20:25:23 motivo volumio[1066]: info: Remote config written successfully Nov 04 20:25:23 motivo volumio[1066]: info: Starting Tunnel 1 Nov 04 20:25:23 motivo volumio[1066]: info: Starting Tunnel Connection Checker Nov 04 20:25:23 motivo volumio[1066]: info: MYVolumio Device enabled Nov 04 20:25:23 motivo volumio[1066]: info: MyVolumio status changed Nov 04 20:25:23 motivo volumio[1066]: info: Streaming services startup Nov 04 20:25:23 motivo volumio[1066]: info: Starting Streaming Daemon Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 04 20:25:23 motivo sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 04 20:25:23 motivo sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:23 motivo volumio[1066]: info: Setting Geolocation for MyVolumio to us3 Nov 04 20:25:23 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:23 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:23 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:23 motivo sudo[1949]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:23 motivo volumio[1066]: error: Cannot start Volumio Streaming Daemon Nov 04 20:25:23 motivo volumio[1066]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 04 20:25:23 motivo volumio[1066]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 04 20:25:23 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:25:23 motivo volumio[1066]: info: Setting Geolocation for MyVolumio to us3 Nov 04 20:25:23 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:23 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:23 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:23 motivo volumio[1066]: info: Successfully Added MyVolumio device Nov 04 20:25:24 motivo volumio[1066]: info: Updating MyVolumio device info Nov 04 20:25:24 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:24 motivo volumio[1066]: info: Updating MyVolumio device info Nov 04 20:25:24 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:24 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:24 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:25 motivo volumio[1066]: info: Successfully Updated MyVolumio device Nov 04 20:25:25 motivo volumio[1066]: info: Successfully Added MyVolumio device Nov 04 20:25:25 motivo volumio[1066]: info: Successfully Updated MyVolumio device Nov 04 20:25:28 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 04 20:25:28 motivo volumio[1066]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 04 20:25:28 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 04 20:25:28 motivo volumio[1066]: info: Received Get System Version Nov 04 20:25:28 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 20:25:28 motivo volumio[1066]: info: Received Get System Info Nov 04 20:25:28 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 20:25:28 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 20:25:28 motivo volumio[1066]: info: Discovery: Getting this device information Nov 04 20:25:28 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:28 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:28 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 20:25:28 motivo sudo[1959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 04 20:25:28 motivo sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:28 motivo systemd[1]: Started MyVolumio SSH Tunnel. Nov 04 20:25:28 motivo sudo[1959]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:28 motivo volumio[1066]: info: Remote SSH Started Nov 04 20:25:28 motivo autossh[1962]: port set to 0, monitoring disabled Nov 04 20:25:28 motivo autossh[1962]: starting ssh (count 1) Nov 04 20:25:28 motivo autossh[1962]: ssh child pid is 1965 Nov 04 20:25:28 motivo volumio[1066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 04 20:25:28 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:28 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:28 motivo volumiossh-tunnel[1961]: Warning: Permanently added '[us3.myvolumio.org]:2222,[159.203.57.91]:2222' (RSA) to the list of known hosts. Nov 04 20:25:29 motivo ntpd[832]: Soliciting pool server 204.197.163.71 Nov 04 20:25:30 motivo ntpd[832]: Soliciting pool server 129.250.35.250 Nov 04 20:25:30 motivo ntpd[832]: Soliciting pool server 162.159.200.123 Nov 04 20:25:30 motivo ntpd[832]: Soliciting pool server 198.137.202.32 Nov 04 20:25:31 motivo ntpd[832]: Soliciting pool server 73.185.182.209 Nov 04 20:25:31 motivo ntpd[832]: Soliciting pool server 46.37.96.107 Nov 04 20:25:31 motivo ntpd[832]: Soliciting pool server 155.248.196.28 Nov 04 20:25:32 motivo ntpd[832]: Soliciting pool server 69.176.84.38 Nov 04 20:25:32 motivo ntpd[832]: Soliciting pool server 12.205.28.193 Nov 04 20:25:32 motivo ntpd[832]: Soliciting pool server 23.186.168.123 Nov 04 20:25:32 motivo volumio[1066]: error: MyVolumio Plugin failed to start in a timely fashion Nov 04 20:25:32 motivo volumio[1066]: [Metrics] CommandRouter: 44s 231.59ms Nov 04 20:25:32 motivo volumio[1066]: info: CoreCommandRouter::volumiosetStartupVolume Nov 04 20:25:32 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:32 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:32 motivo volumio[1066]: info: CoreCommandRouter::Close All Modals sent Nov 04 20:25:32 motivo volumio[1066]: info: CoreCommandRouter::Close All Modals sent Nov 04 20:25:33 motivo ntpd[832]: Soliciting pool server 2001:470:e114::123 Nov 04 20:25:33 motivo ntpd[832]: Soliciting pool server 50.117.3.95 Nov 04 20:25:33 motivo ntpd[832]: Soliciting pool server 216.144.228.179 Nov 04 20:25:33 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 04 20:25:33 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 04 20:25:33 motivo volumio[1066]: No protocol specified Nov 04 20:25:33 motivo volumio[1066]: xcb_connection_has_error() returned true Nov 04 20:25:33 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 04 20:25:34 motivo ntpd[832]: Soliciting pool server 72.14.186.59 Nov 04 20:25:35 motivo ntpd[832]: Soliciting pool server 66.42.71.197 Nov 04 20:25:36 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 20:25:36 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 20:25:36 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 20:25:36 motivo volumio-remote-updater[737]: No test mode Nov 04 20:25:36 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 04 20:25:36 motivo volumio-remote-updater[737]: No alpha test mode Nov 04 20:25:36 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 04 20:25:36 motivo volumio[1066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 04 20:25:36 motivo volumio[1066]: info: CoreCommandRouter::volumioGetState Nov 04 20:25:36 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:36 motivo volumio[1066]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 04 20:25:36 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 04 20:25:40 motivo volumio[1066]: info: BOOT COMPLETED Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 20:25:41 motivo volumio[1066]: info: Not Reporting Auto name since its the default one Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 04 20:25:41 motivo volumio[1066]: No protocol specified Nov 04 20:25:41 motivo volumio[1066]: xcb_connection_has_error() returned true Nov 04 20:25:41 motivo sudo[1994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 04 20:25:41 motivo sudo[1994]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:41 motivo sudo[1994]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:41 motivo sudo[1996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 04 20:25:41 motivo sudo[1996]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:25:41 motivo systemd[1]: Started RAAT DAEMON. Nov 04 20:25:41 motivo sudo[1996]: pam_unix(sudo:session): session closed for user root Nov 04 20:25:41 motivo volumio[1066]: info: Raat Daemon started successfully Nov 04 20:25:41 motivo volumio[1066]: info: Executing endpoint restartRAATSocket Nov 04 20:25:41 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 04 20:25:41 motivo volumio[1066]: info: CorePlayQueue::getTrack 0 Nov 04 20:25:41 motivo volumio[1066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 04 20:25:42 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:42 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 04 20:25:42 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 04 20:25:42 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 04 20:25:42 motivo volumio[1066]: info: CoreCommandRouter::volumioGetBrowseSources Nov 04 20:25:42 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 20:25:44 motivo volumio[1066]: info: RAAT: Requesting Headphone Status Nov 04 20:25:44 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 04 20:25:44 motivo volumio[1066]: info: MCU Signalled Headphone Mode Disabled Nov 04 20:25:44 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Nov 04 20:25:44 motivo volumio[1066]: info: Reporting Headphone State: false Nov 04 20:25:44 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:44 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 20:25:44 motivo volumio[1066]: info: Updating RAAT Signal Path Nov 04 20:26:03 motivo volumio[1066]: info: MYVolumio Device disabled Nov 04 20:26:03 motivo volumio[1066]: info: MyVolumio status changed Nov 04 20:26:03 motivo volumio[1066]: info: Streaming services startup Nov 04 20:26:03 motivo volumio[1066]: info: Starting Streaming Daemon Nov 04 20:26:03 motivo volumio[1066]: info: [MyVolumio PluginManager] Device deactivated, disabling myvolumio plugins... Nov 04 20:26:03 motivo volumio[1066]: info: ___________ STOPPING MYVOLUMIO PLUGINS ___________ Nov 04 20:26:03 motivo volumio[1066]: info: Nov 04 20:26:03 motivo sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 04 20:26:03 motivo sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:26:04 motivo sudo[2061]: pam_unix(sudo:session): session closed for user root Nov 04 20:26:04 motivo volumio[1066]: error: Cannot start Volumio Streaming Daemon Nov 04 20:26:04 motivo volumio[1066]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 04 20:26:04 motivo volumio[1066]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 04 20:26:08 motivo volumio[1066]: info: MYVolumio Device enabled Nov 04 20:26:08 motivo volumio[1066]: info: MyVolumio status changed Nov 04 20:26:08 motivo volumio[1066]: info: Streaming services startup Nov 04 20:26:08 motivo volumio[1066]: info: Starting Streaming Daemon Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 04 20:26:08 motivo volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Nov 04 20:26:08 motivo volumio[1066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 20:26:08 motivo sudo[2067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 04 20:26:08 motivo sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 20:26:08 motivo volumio[1066]: Error: Unable to resolve or reject the same promise twice Nov 04 20:26:08 motivo volumio[1066]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 04 20:26:08 motivo volumio[1066]: at Array. (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5676) Nov 04 20:26:08 motivo volumio[1066]: at Promise.reject (/volumio/node_modules/kew/kew.js:204:26) Nov 04 20:26:08 motivo volumio[1066]: at nextTickCallback (/volumio/node_modules/kew/kew.js:49:11) Nov 04 20:26:08 motivo volumio[1066]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 04 20:26:08 motivo volumio[1066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 20:26:08 motivo sudo[2067]: pam_unix(sudo:session): session closed for user root Nov 04 20:26:09 motivo sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 20:25 Nov 04 20:26:09 motivo sudo[2078]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 12:42:34 PM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="03b8c851b751de445da30842a70a04d1"