-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2026-04-13 18:29:20 CEST. -- Apr 13 18:28:29 motivo sshd[6787]: Accepted password for volumio from 192.168.0.136 port 51860 ssh2 Apr 13 18:28:29 motivo sshd[6787]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Apr 13 18:28:29 motivo systemd-logind[721]: New session 7 of user volumio. Apr 13 18:28:29 motivo systemd[1]: Started Session 7 of user volumio. Apr 13 18:28:30 motivo sshd[6796]: Received disconnect from 192.168.0.136 port 51860:11: disconnected by user Apr 13 18:28:30 motivo sshd[6796]: Disconnected from user volumio 192.168.0.136 port 51860 Apr 13 18:28:30 motivo sshd[6787]: pam_unix(sshd:session): session closed for user volumio Apr 13 18:28:30 motivo systemd[1]: session-7.scope: Succeeded. Apr 13 18:28:30 motivo systemd-logind[721]: Session 7 logged out. Waiting for processes to exit. Apr 13 18:28:30 motivo systemd-logind[721]: Removed session 7. Apr 13 18:28:31 motivo sudo[6069]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:31 motivo sudo[6879]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/killall node Apr 13 18:28:31 motivo sudo[6879]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 13 18:28:31 motivo sudo[6879]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:31 motivo volumio5-onboarding[1607]: time=2026-04-13T18:28:31.835+02:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Apr 13 18:28:31 motivo sudo[6881]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Apr 13 18:28:31 motivo vtcs[6403]: [2026-04-13 18:28:31.838] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 13 18:28:31 motivo volumio5-onboarding[1607]: time=2026-04-13T18:28:31.838+02:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:52010->127.0.0.1:3000: read: connection reset by peer" Apr 13 18:28:31 motivo volumio-remote-updater[715]: [2026-04-13 18:28:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 13 18:28:31 motivo volumio-remote-updater[715]: [2026-04-13 18:28:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 13 18:28:31 motivo qobuz-connect[6300]: 20260413 18:28:31.839 [6300.6300] INFO SampleApp: Socket closed Apr 13 18:28:31 motivo sudo[6881]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Apr 13 18:28:31 motivo systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Apr 13 18:28:31 motivo sudo[6318]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:31 motivo systemd[1]: volumio.service: Succeeded. Apr 13 18:28:31 motivo systemd[1]: Started dynamicswap service. Apr 13 18:28:31 motivo systemd[1]: dynamicswap.service: Succeeded. Apr 13 18:28:31 motivo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 13 18:28:31 motivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Apr 13 18:28:31 motivo systemd[1]: Stopped Volumio Backend Module. Apr 13 18:28:32 motivo systemd[1]: Started Volumio Backend Module. Apr 13 18:28:32 motivo systemd[1]: Started dynamicswap service. Apr 13 18:28:32 motivo systemd[1]: dynamicswap.service: Succeeded. Apr 13 18:28:33 motivo volumio[6889]: info: ------------------------------------------- Apr 13 18:28:33 motivo volumio[6889]: info: ----- Volumio3 ---- Apr 13 18:28:33 motivo volumio[6889]: info: ------------------------------------------- Apr 13 18:28:33 motivo volumio[6889]: info: ----- System startup ---- Apr 13 18:28:33 motivo volumio[6889]: info: ------------------------------------------- Apr 13 18:28:33 motivo volumio[6889]: info: MYVOLUMIO Environment detected Apr 13 18:28:33 motivo volumio[6889]: info: Plugin folders cleanup Apr 13 18:28:33 motivo volumio[6889]: info: Scanning into folder /volumio/app/plugins/ Apr 13 18:28:33 motivo volumio[6889]: info: Scanning category audio_interface Apr 13 18:28:33 motivo volumio[6889]: info: Scanning category miscellanea Apr 13 18:28:33 motivo volumio[6889]: info: Scanning category music_service Apr 13 18:28:33 motivo volumio[6889]: info: Scanning category plugins.json Apr 13 18:28:33 motivo volumio[6889]: info: Scanning category system_controller Apr 13 18:28:33 motivo volumio[6889]: info: Scanning category user_interface Apr 13 18:28:33 motivo volumio[6889]: info: Scanning into folder /data/plugins/ Apr 13 18:28:33 motivo volumio[6889]: info: Scanning category music_service Apr 13 18:28:33 motivo volumio[6889]: info: Scanning category plugins.json Apr 13 18:28:33 motivo volumio[6889]: info: Plugin folders cleanup completed Apr 13 18:28:33 motivo volumio[6889]: info: ------------------------------------------- Apr 13 18:28:33 motivo volumio[6889]: info: ----- Core plugins startup ---- Apr 13 18:28:33 motivo volumio[6889]: info: ------------------------------------------- Apr 13 18:28:33 motivo volumio[6889]: info: Loading plugins from folder /volumio/app/plugins/ Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin bluetooth to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin multiroom to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin upnp to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin metavolumio to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin cd_controller to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin raat to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 13 18:28:33 motivo volumio[6889]: info: Loading plugins from folder /data/plugins/ Apr 13 18:28:33 motivo volumio[6889]: info: Loading plugin "system"... Apr 13 18:28:33 motivo volumio[6889]: info: Loading plugin "appearance"... Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "network"... Apr 13 18:28:34 motivo volumio[6889]: info: Refreshing Cached IP Addresses Apr 13 18:28:34 motivo sudo[6916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 18:28:34 motivo sudo[6916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:34 motivo sudo[6916]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:34 motivo sudo[6918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 18:28:34 motivo sudo[6918]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "services"... Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "volumio5onboarding"... Apr 13 18:28:34 motivo sudo[6918]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:34 motivo sudo[6927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 13 18:28:34 motivo sudo[6927]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "alsa_controller"... Apr 13 18:28:34 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "wizard"... Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "networkfs"... Apr 13 18:28:34 motivo volumio[6889]: info: Starting Udev Watcher for removable devices Apr 13 18:28:34 motivo sudo[6944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=massi,password=M5$HRzzEG7hmLKtrb#QB,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.2/music /mnt/NAS/NAS Apr 13 18:28:34 motivo sudo[6944]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:34 motivo volumio[6889]: info: Ignoring mount for partition: boot Apr 13 18:28:34 motivo volumio[6889]: info: Ignoring mount for partition: volumio Apr 13 18:28:34 motivo volumio[6889]: info: Ignoring mount for partition: volumio_data Apr 13 18:28:34 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "volumio_command_line_client"... Apr 13 18:28:34 motivo kernel: CIFS: Attempting to mount //192.168.0.2/music Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "upnp"... Apr 13 18:28:34 motivo volumio[6889]: info: [1776097714548] Starting Upmpd Daemon Apr 13 18:28:34 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "my_music"... Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "mpd"... Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "upnp_browser"... Apr 13 18:28:34 motivo volumio[6889]: info: Starting UPNP Browser Apr 13 18:28:34 motivo volumio[6889]: info: Loading plugin "bluetooth"... Apr 13 18:28:35 motivo volumio[6889]: info: [1776097715081] Starting BluetoothController Apr 13 18:28:35 motivo volumio[6889]: info: Loading plugin "alarm-clock"... Apr 13 18:28:35 motivo volumio[6889]: info: Loading plugin "manifestui"... Apr 13 18:28:35 motivo volumio[6889]: info: Loading plugin "metavolumio"... Apr 13 18:28:36 motivo volumio[6889]: info: Loading plugin "airplay_emulation"... Apr 13 18:28:36 motivo volumio[6889]: info: Starting Shairport Sync Apr 13 18:28:36 motivo volumio[6889]: info: Loading plugin "cd_controller"... Apr 13 18:28:36 motivo volumio[6889]: info: Loading plugin "last_100"... Apr 13 18:28:36 motivo volumio[6889]: info: Loading plugin "qobuzconnect"... Apr 13 18:28:36 motivo volumio[6889]: info: Loading plugin "raat"... Apr 13 18:28:36 motivo volumio[6889]: info: RAAT Plugin loaded Apr 13 18:28:36 motivo volumio[6889]: info: Adding restartRAATSocket REST API Endpoint Apr 13 18:28:36 motivo volumio[6889]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Apr 13 18:28:36 motivo volumio[6889]: info: Loading plugin "streaming_services"... Apr 13 18:28:36 motivo volumio-remote-updater[715]: [2026-04-13 18:28:36] [connect] Successful connection Apr 13 18:28:36 motivo volumio[6889]: info: Starting Streaming Service Transparent Proxy Apr 13 18:28:36 motivo volumio[6889]: info: Loading plugin "tidalconnect"... Apr 13 18:28:37 motivo volumio[6889]: info: Loading plugin "webradio"... Apr 13 18:28:37 motivo volumio[6889]: info: Loading plugin "i2s_dacs"... Apr 13 18:28:37 motivo volumio[6889]: info: I2S DAC not set, start Auto-detection Apr 13 18:28:37 motivo volumio[6889]: info: Loading plugin "volumiodiscovery"... Apr 13 18:28:37 motivo volumio[6889]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 18:28:37 motivo volumio[6889]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 18:28:37 motivo volumio[6889]: *** WARNING *** For more information see Apr 13 18:28:37 motivo node[6889]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 13 18:28:37 motivo volumio[6889]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 18:28:37 motivo volumio[6889]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 18:28:37 motivo volumio[6889]: *** WARNING *** For more information see Apr 13 18:28:37 motivo node[6889]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 18:28:37 motivo node[6889]: *** WARNING *** For more information see Apr 13 18:28:37 motivo node[6889]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 13 18:28:37 motivo node[6889]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 13 18:28:37 motivo node[6889]: *** WARNING *** For more information see Apr 13 18:28:37 motivo volumio[6889]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 13 18:28:37 motivo volumio[6889]: info: Discovery: Started advertising with name: Motivo Apr 13 18:28:37 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 18:28:37 motivo volumio[6889]: info: Loading plugin "multiroom"... Apr 13 18:28:37 motivo volumio[6889]: STREAMING PROXY: Starting server on port 3245 Apr 13 18:28:37 motivo volumio[6889]: Node JS runtime: 14 Apr 13 18:28:37 motivo sudo[6927]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:38 motivo volumio[6889]: info: Applying required configuration parameters for plugin multiroom Apr 13 18:28:38 motivo sudo[6958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 13 18:28:38 motivo sudo[6958]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:38 motivo sudo[6958]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:38 motivo volumio[6889]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Apr 13 18:28:38 motivo volumio[6889]: info: MRS: MultiRoom plugin initialized Apr 13 18:28:38 motivo volumio[6889]: info: MRS: STOPPING SNAPCLIENT Apr 13 18:28:38 motivo volumio[6889]: info: MRS: Snap server stop Apr 13 18:28:38 motivo sudo[6974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 13 18:28:38 motivo volumio[6889]: info: MRS: STOPPING volumioStreaming Apr 13 18:28:38 motivo sudo[6974]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:38 motivo sudo[6976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 13 18:28:38 motivo sudo[6976]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:38 motivo sudo[6974]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:38 motivo sudo[6979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 13 18:28:38 motivo sudo[6979]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:38 motivo volumio[6889]: info: Loading plugin "outputs"... Apr 13 18:28:38 motivo sudo[6976]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:38 motivo volumio[6889]: info: Loading plugin "albumart"... Apr 13 18:28:38 motivo sudo[6983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 13 18:28:38 motivo sudo[6983]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:38 motivo sudo[6979]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:38 motivo sudo[6983]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:38 motivo volumio[6889]: info: Plugin example_plugin is not enabled Apr 13 18:28:38 motivo volumio[6889]: info: Loading plugin "hi_res_audio"... Apr 13 18:28:38 motivo volumio[6889]: Forking 3 albumart workers Apr 13 18:28:39 motivo volumio[6889]: Starting albumart workers Apr 13 18:28:39 motivo volumio[6889]: info: Applying required configuration parameters for plugin hi_res_audio Apr 13 18:28:39 motivo volumio[6889]: info: Loading plugin "inputs"... Apr 13 18:28:39 motivo volumio[6889]: Starting albumart workers Apr 13 18:28:39 motivo volumio[6889]: Starting albumart workers Apr 13 18:28:39 motivo volumio[6889]: info: Loading plugin "qobuz"... Apr 13 18:28:40 motivo volumio[6889]: info: Loading plugin "smart_inputs"... Apr 13 18:28:40 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 13 18:28:40 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Apr 13 18:28:40 motivo sudo[6944]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:40 motivo volumio[6889]: info: Loading plugin "tidal"... Apr 13 18:28:41 motivo volumio[6889]: info: Loading plugin "motivocontrol"... Apr 13 18:28:41 motivo volumio[6889]: info: Initializing System Ready GPIO for kernel version: 6.6.62-v8+ Apr 13 18:28:41 motivo volumio[6889]: info: Adding this device properties Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Apr 13 18:28:41 motivo volumio[6889]: info: Setting Additional Device Volumio Properties: [object Object] Apr 13 18:28:41 motivo volumio[6889]: info: Checking For Motivo Hardware Apr 13 18:28:41 motivo volumio[6889]: info: Loading plugin "updater_comm"... Apr 13 18:28:41 motivo volumio[6889]: info: Plugin mpdemulation is not enabled Apr 13 18:28:41 motivo volumio[6889]: info: Loading plugin "rest_api"... Apr 13 18:28:41 motivo volumio[6889]: info: Loading plugin "websocket"... Apr 13 18:28:41 motivo volumio[6889]: info: Starting Socket.io Server version 2.3.0 Apr 13 18:28:41 motivo volumio[6889]: info: Loading plugin "musicserver"... Apr 13 18:28:41 motivo volumio[6889]: info: Loading plugin "podcast"... Apr 13 18:28:41 motivo volumio[6889]: info: ControllerPodcast::constructor Apr 13 18:28:41 motivo volumio[6889]: info: Loading i18n strings for locale it Apr 13 18:28:41 motivo volumio[6889]: Updating browse sources language Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::initPlayerControls Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 18:28:41 motivo volumio[6889]: Express server listening on port 3000 Apr 13 18:28:41 motivo volumio[6889]: [Metrics] WebUI: 9s 106.05ms Apr 13 18:28:41 motivo volumio[6889]: info: CoreStateMachine::resetVolumioState Apr 13 18:28:41 motivo volumio[6889]: info: CoreStateMachine::getcurrentVolume Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 18:28:41 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:41 motivo volumio[6889]: info: Volumio Network Manager: Network status updated: 2 Apr 13 18:28:42 motivo volumio[6889]: info: Motivo Hardware found Apr 13 18:28:42 motivo volumio[6889]: info: MRS: Removed streaming files Apr 13 18:28:42 motivo volumio[6889]: info: MRS: volumioStreaming STOPPED Apr 13 18:28:42 motivo volumio[6889]: info: MRS: SNAPSERVER STOPPED Apr 13 18:28:42 motivo volumio[6889]: info: MRS: SNAPCLIENT STOPPED Apr 13 18:28:42 motivo volumio[6889]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Apr 13 18:28:42 motivo volumio[6889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Apr 13 18:28:42 motivo volumio[6889]: 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: 2 Apr 13 18:28:42 motivo volumio[6889]: 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 Apr 13 18:28:42 motivo volumio[6889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Apr 13 18:28:42 motivo volumio[6889]: info: Received Get System Info Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 18:28:42 motivo volumio[6889]: info: Discovery: Getting this device information Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:42 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 18:28:42 motivo volumio[6889]: info: Reloading queue from file Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 13 18:28:42 motivo volumio[6889]: info: VolumeController:: Volume=0 Mute =false Apr 13 18:28:42 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:42 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:42 motivo volumio[6889]: info: CoreStateMachine::updateTrackBlock Apr 13 18:28:42 motivo volumio[6889]: info: CorePlayQueue::getTrackBlock Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioRetrievevolume Apr 13 18:28:42 motivo volumio[6889]: info: Setting Device type: Raspberry PI Apr 13 18:28:42 motivo volumio[6889]: info: CoreStateMachine::setRepeat true single undefined Apr 13 18:28:42 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:42 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:42 motivo volumio[6889]: info: CoreStateMachine::setRandom null Apr 13 18:28:42 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:42 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:42 motivo volumio[6889]: info: Completed loading Core Plugins Apr 13 18:28:42 motivo volumio[6889]: info: Preparing to generate the ALSA configuration file Apr 13 18:28:42 motivo volumio[6889]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 13 18:28:42 motivo volumio[6889]: info: Reading ALSA contributions from plugins. Apr 13 18:28:42 motivo volumio[6889]: info: VolumeController:: Volume=0 Mute =false Apr 13 18:28:42 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:42 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:42 motivo volumio[6889]: info: Discovery: adding ff3b1f53-d553-491f-9136-3b0ec7211453 Apr 13 18:28:42 motivo volumio[6889]: info: Discovery: Found device Motivo Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:42 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:42 motivo volumio[6889]: info: MRS: Pushing multiroomSync output for this device Apr 13 18:28:42 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:42 motivo volumio[6889]: info: Adding audio output: Apr 13 18:28:42 motivo volumio[6889]: info: Adding audio output: Apr 13 18:28:42 motivo volumio[6889]: info: Asound.conf file unchanged, so no further update is needed Apr 13 18:28:42 motivo volumio[6889]: info: Output device has changed, restarting MPD Apr 13 18:28:42 motivo sudo[7061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 18:28:42 motivo volumio[6889]: info: Output device has changed, restarting Shairport Sync Apr 13 18:28:42 motivo sudo[7061]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:42 motivo sudo[7061]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:42 motivo sudo[7064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 18:28:42 motivo sudo[7064]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:42 motivo systemd[1]: Stopping Music Player Daemon... Apr 13 18:28:42 motivo volumio[6889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 18:28:42 motivo volumio[6889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:42 motivo systemd[1]: mpd.service: Succeeded. Apr 13 18:28:42 motivo systemd[1]: Stopped Music Player Daemon. Apr 13 18:28:42 motivo volumio[6889]: info: ___________ START PLUGINS ___________ Apr 13 18:28:42 motivo systemd[1]: Starting Music Player Daemon... Apr 13 18:28:42 motivo volumio[6889]: info: ControllerMpd::onStart: Initializing MPD Apr 13 18:28:42 motivo volumio[6889]: info: Creating MPD Configuration file Apr 13 18:28:42 motivo sudo[7074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 13 18:28:42 motivo sudo[7074]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:42 motivo sudo[7077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 13 18:28:42 motivo sudo[7077]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:42 motivo sudo[7074]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:42 motivo sudo[7077]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 18:28:42 motivo volumio[6889]: info: [1776097722792] CoreMusicLibrary::Adding element Server multimediali Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:28:42 motivo sudo[7070]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 13 18:28:42 motivo sudo[7070]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:42 motivo volumio[6889]: info: UPNP Browser: Client initialized successfully Apr 13 18:28:42 motivo sudo[7080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 13 18:28:42 motivo sudo[7080]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:42 motivo sudo[7070]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 13 18:28:42 motivo volumio[6889]: info: Adding METAVOLUMIO REST API Endpoints Apr 13 18:28:42 motivo volumio[6889]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 13 18:28:42 motivo volumio[6889]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 13 18:28:42 motivo volumio[6889]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 13 18:28:42 motivo volumio[6889]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:42 motivo sudo[7085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Motivo Apr 13 18:28:42 motivo sudo[7085]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:42 motivo sudo[7085]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:42 motivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 13 18:28:42 motivo systemd[1]: mpd.service: Succeeded. Apr 13 18:28:42 motivo systemd[1]: Stopped Music Player Daemon. Apr 13 18:28:42 motivo systemd[1]: Starting Music Player Daemon... Apr 13 18:28:42 motivo volumio[6889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:42 motivo volumio[6889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 13 18:28:42 motivo volumio[6889]: info: Preparing CD Folders Apr 13 18:28:42 motivo volumio[6889]: info: Adding CD REST API Endpoints Apr 13 18:28:42 motivo volumio[6889]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 13 18:28:42 motivo volumio[6889]: info: Starting UDEV Watcher for CD Apr 13 18:28:42 motivo volumio[6889]: info: Detecting CD presence with UDEV Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 13 18:28:42 motivo sudo[7090]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 13 18:28:42 motivo sudo[7090]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 18:28:42 motivo volumio[6889]: info: [1776097722958] CoreMusicLibrary::Adding element Last_100 Apr 13 18:28:42 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:28:42 motivo sudo[7090]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:42 motivo volumio[6889]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Apr 13 18:28:42 motivo volumio[6889]: info: QobuzConnect: Starting Qobuz Connect socket and service Apr 13 18:28:43 motivo volumio[6889]: info: Starting RAAT Plugin Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Apr 13 18:28:43 motivo volumio[6889]: info: Additional UI Settings Added for plugin music_service/raat Apr 13 18:28:43 motivo volumio[6889]: info: Registering DSP Elements listener and retrieving current ones Apr 13 18:28:43 motivo volumio[6889]: info: Additional DSP elements updated Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:43 motivo volumio[6889]: info: Updating RAAT Signal Path Apr 13 18:28:43 motivo volumio[6889]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 13 18:28:43 motivo volumio[6889]: info: Streaming services startup Apr 13 18:28:43 motivo volumio[6889]: info: Starting Streaming Daemon Apr 13 18:28:43 motivo sudo[7100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 13 18:28:43 motivo sudo[7100]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:43 motivo sudo[7100]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:43 motivo sudo[7103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 13 18:28:43 motivo sudo[7103]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:43 motivo sudo[7104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 13 18:28:43 motivo sudo[7104]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:43 motivo sudo[7103]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:43 motivo systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 13 18:28:43 motivo qobuz-connect[6300]: 20260413 18:28:43.109 [6300.6300] INFO SampleApp: Stopping Local configuration server Apr 13 18:28:43 motivo systemd[1]: qobuz-connect.service: Succeeded. Apr 13 18:28:43 motivo systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 18:28:43 motivo volumio[6889]: info: [1776097723144] CoreMusicLibrary::Adding element Webradio Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 18:28:43 motivo volumio[6889]: info: Initializing BBC Radios Apr 13 18:28:43 motivo sudo[7113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 13 18:28:43 motivo sudo[7113]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:43 motivo systemd[1]: Started Volumio Qobuz Connect Service. Apr 13 18:28:43 motivo sudo[7104]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:43 motivo systemd[1]: Stopping Volumio Tidal Connect Service... Apr 13 18:28:43 motivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 13 18:28:43 motivo systemd[1]: vtcs.service: Succeeded. Apr 13 18:28:43 motivo systemd[1]: Stopped Volumio Tidal Connect Service. Apr 13 18:28:43 motivo sudo[7113]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 13 18:28:43 motivo volumio[6889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:43 motivo volumio[6889]: error: Hi Res Audio Failed Login: Missing Login Data Apr 13 18:28:43 motivo volumio[6889]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 13 18:28:43 motivo volumio[6889]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 13 18:28:43 motivo volumio[6889]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 13 18:28:43 motivo volumio[6889]: info: Initializing Serial Communication on port /dev/ttyAMA4 Apr 13 18:28:43 motivo volumio[6889]: info: Touch Event Listener Process Starting Apr 13 18:28:43 motivo volumio[6889]: info: Adding inputs REST Endpoints Apr 13 18:28:43 motivo volumio[6889]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 13 18:28:43 motivo volumio[6889]: info: Scanning Audio Inputs Apr 13 18:28:43 motivo sudo[7131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Apr 13 18:28:43 motivo sudo[7131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:43 motivo volumio[6889]: info: Checking against Known Cards name Apr 13 18:28:43 motivo volumio[6889]: info: Checking against Known Cards name Apr 13 18:28:43 motivo volumio[6889]: info: Adding Server instance for streaming Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 18:28:43 motivo volumio[6889]: info: [1776097723433] CoreMusicLibrary::Adding element Music Server Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:28:43 motivo volumio[6889]: Cannot find translation for source Music Server Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 13 18:28:43 motivo volumio[6889]: info: [1776097723442] CoreMusicLibrary::Adding element Podcast Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:28:43 motivo volumio[6889]: Cannot find translation for source Music Server Apr 13 18:28:43 motivo volumio[6889]: Cannot find translation for source Podcast Apr 13 18:28:43 motivo volumio[6889]: info: Volumio Calling Home Apr 13 18:28:43 motivo volumio[6889]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Apr 13 18:28:43 motivo volumio[6889]: (node:6889) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Apr 13 18:28:43 motivo volumio[6889]: (Use `node --trace-deprecation ...` to show where the warning was created) Apr 13 18:28:43 motivo volumio[6889]: info: Stopping AccessToken refresher cron for QOBUZ Apr 13 18:28:43 motivo volumio[6889]: info: AccessToken refresher cron started for QOBUZ Apr 13 18:28:43 motivo volumio[6889]: info: Adding TIDAL REST API Endpoints Apr 13 18:28:43 motivo volumio[6889]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 13 18:28:43 motivo volumio[6889]: info: Adding QOBUZ REST API Endpoints Apr 13 18:28:43 motivo volumio[6889]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 13 18:28:43 motivo volumio[6889]: info: Serial port opened successfully Apr 13 18:28:43 motivo volumio[6889]: info: Sending serial start messages Apr 13 18:28:43 motivo volumio[6889]: info: Display with brightness control is connected, setting display brightness Apr 13 18:28:43 motivo volumio[6889]: info: Setting Display Brightness to 12 Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:43 motivo volumio[6889]: info: Reporting MCU Network Status: 2 Apr 13 18:28:43 motivo volumio[6889]: error: Cannot start Volumio Streaming Daemon Apr 13 18:28:43 motivo volumio[6889]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 13 18:28:43 motivo volumio[6889]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 13 18:28:43 motivo volumio[6889]: info: Bluetooth name changed to Motivo Apr 13 18:28:43 motivo volumio[6889]: info: MPD Permissions set Apr 13 18:28:43 motivo volumio[6889]: info: MPD Permissions set Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Apr 13 18:28:43 motivo volumio[6889]: info: Setting Device Volume Override Apr 13 18:28:43 motivo volumio[6889]: info: Applying Volume Override Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 13 18:28:43 motivo volumio[6889]: info: Updating Volume Controller Parameters: Device: 5 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 13 18:28:43 motivo volumio[6889]: info: Enabling external Volume Control Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:43 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:43 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:43 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:43 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:43 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:43 motivo volumio[6889]: info: Executing endpoint qc_getconfig Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 13 18:28:43 motivo mpd[7095]: Apr 13 18:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 13 18:28:43 motivo systemd[1]: Started Music Player Daemon. Apr 13 18:28:43 motivo qobuz-connect[7112]: 20260413 18:28:43.971 [7112.7112] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 13 18:28:43 motivo sudo[7064]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:43 motivo sudo[7080]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:43 motivo qobuz-connect[7112]: 20260413 18:28:43.978 [7112.7112] INFO VolumeManager: [0x1596958]: Setting new playback volume: 75 Apr 13 18:28:43 motivo qobuz-connect[7112]: 20260413 18:28:43.978 [7112.7112] INFO VolumeManager: [0x1596958]: Setting new mute state: 0 Apr 13 18:28:43 motivo qobuz-connect[7112]: 20260413 18:28:43.978 [7112.7112] INFO QobuzConnect: [0x1597328]: Client initialized! Apr 13 18:28:43 motivo qobuz-connect[7112]: 20260413 18:28:43.978 [7112.7112] INFO SampleApp: Starting Avahi advertising, name: Motivo, service name: _qobuz-connect._tcp Apr 13 18:28:43 motivo volumio[6889]: info: Volumio called home Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 13 18:28:43 motivo volumio[6889]: info: Setting Additional System Software info: Hardware Revision: 2.4 Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 13 18:28:43 motivo volumio[6889]: info: Setting HW Firmware info: undefined Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 13 18:28:43 motivo qobuz-connect[7112]: 20260413 18:28:43.994 [7112.7112] INFO LocalConfigManager: [0x1596238]: Starting Local Configuration server Apr 13 18:28:43 motivo qobuz-connect[7112]: 20260413 18:28:43.994 [7112.7112] INFO SampleApp: Starting Local configuration server Apr 13 18:28:43 motivo volumio[6889]: info: Setting HW Version info: 2.4 Apr 13 18:28:43 motivo qobuz-connect[7112]: 20260413 18:28:43.995 [7112.7112] INFO SampleApp: Connected to UNIX socket client 0x158bf60 Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 13 18:28:43 motivo volumio[6889]: info: Setting Additional System Software info: Hardware Revision: 2.4, Firmware Version: 0.2.0 Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 13 18:28:43 motivo volumio[6889]: info: Setting HW Firmware info: 0.2.0 Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 13 18:28:43 motivo volumio[6889]: info: Setting HW Version info: 2.4 Apr 13 18:28:43 motivo volumio[6889]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 13 18:28:43 motivo volumio[6889]: info: MCU Signalled Headphone Mode Disabled Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 13 18:28:43 motivo volumio[6889]: info: Reporting Headphone State: false Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:43 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:43 motivo volumio[6889]: info: Updating RAAT Signal Path Apr 13 18:28:43 motivo volumio[6889]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 13 18:28:43 motivo volumio[6889]: info: MCU Signalled Sleep Mode Disabled Apr 13 18:28:44 motivo volumio[6889]: info: Enabling Advanced system settings configuration Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Apr 13 18:28:44 motivo volumio[6889]: info: Additional UI Settings Added for plugin music_service/inputs Apr 13 18:28:44 motivo volumio[6889]: info: MCU Signalled Auto Boot Mode On Power Disabled Apr 13 18:28:44 motivo sudo[7149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Apr 13 18:28:44 motivo sudo[7149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:44 motivo sudo[7149]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:44 motivo volumio[6889]: info: Adding audio output: Apr 13 18:28:44 motivo volumio[6889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 13 18:28:44 motivo volumio[6889]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 13 18:28:44 motivo volumio[6889]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 13 18:28:44 motivo qobuz-connect[7112]: 20260413 18:28:44.120 [7112.7112] INFO SampleApp: Playback volume changed: 75 Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:28:44 motivo volumio[6889]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Apr 13 18:28:44 motivo volumio[6889]: info: Adding audio output: Apr 13 18:28:44 motivo volumio[6889]: info: Adding audio output: Apr 13 18:28:44 motivo volumio[6889]: info: Adding audio output: Apr 13 18:28:44 motivo volumio[6889]: info: Adding audio output: Apr 13 18:28:44 motivo volumio[6889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:44 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:44 motivo sudo[7162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Apr 13 18:28:44 motivo sudo[7162]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:44 motivo sudo[7162]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:44 motivo volumio[6889]: info: Adding audio output: Apr 13 18:28:44 motivo volumio[6889]: error: MPD error: The expression evaluated to a falsy value: Apr 13 18:28:44 motivo volumio[6889]: assert.ok(self.idling) Apr 13 18:28:44 motivo volumio[6889]: error: The expression evaluated to a falsy value: Apr 13 18:28:44 motivo volumio[6889]: assert.ok(self.idling) Apr 13 18:28:44 motivo volumio[6889]: info: MPD running with PID7095 Apr 13 18:28:44 motivo volumio[6889]: ,establishing connection Apr 13 18:28:44 motivo sudo[7179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 18:28:44 motivo sudo[7179]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:44 motivo sudo[7179]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:44 motivo volumio[6889]: error: updateQueue error: null Apr 13 18:28:44 motivo sudo[7181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 18:28:44 motivo sudo[7181]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:44 motivo volumio[6889]: 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 Apr 13 18:28:44 motivo sudo[7181]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:44 motivo volumio[6889]: info: Starting Shairport Sync Apr 13 18:28:44 motivo volumio[6889]: info: Starting Shairport Sync Apr 13 18:28:44 motivo sudo[7185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 18:28:44 motivo sudo[7185]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:44 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 13 18:28:44 motivo volumio[6889]: info: Starting Shairport Sync Apr 13 18:28:44 motivo systemd[1]: shairport-sync.service: Succeeded. Apr 13 18:28:44 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 13 18:28:44 motivo sudo[7188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 18:28:44 motivo sudo[7188]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:44 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 13 18:28:44 motivo sudo[7185]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:44 motivo volumio[6889]: info: Adding Advanced Audio Settings via Serial API Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Apr 13 18:28:44 motivo volumio[6889]: info: Additional UI Settings Added for plugin music_service/inputs Apr 13 18:28:44 motivo volumio[6889]: info: Adding Inputs via Serial API Apr 13 18:28:44 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:44 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:44 motivo sudo[7192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 13 18:28:44 motivo sudo[7192]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:44 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 13 18:28:44 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 13 18:28:44 motivo systemd[1]: shairport-sync.service: Succeeded. Apr 13 18:28:44 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:44 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:44 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:44 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:44 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 13 18:28:44 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:44 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:44 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:44 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:44 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:44 motivo sudo[7188]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:44 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 13 18:28:44 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 13 18:28:44 motivo systemd[1]: shairport-sync.service: Succeeded. Apr 13 18:28:44 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 13 18:28:44 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 13 18:28:44 motivo sudo[7192]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:44 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:44 motivo volumio[6889]: info: Adding Advanced Audio Settings via Serial API Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Apr 13 18:28:44 motivo volumio[6889]: error: updateQueue error: null Apr 13 18:28:44 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: Shairport-Sync Started Apr 13 18:28:45 motivo volumio[6889]: Error adding Membership: Error: addMembership EINVAL Apr 13 18:28:45 motivo volumio[6889]: info: Shairport-Sync Started Apr 13 18:28:45 motivo volumio[6889]: info: Shairport-Sync Started Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetQueue Apr 13 18:28:45 motivo volumio[6889]: info: CoreStateMachine::getQueue Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getQueue Apr 13 18:28:45 motivo volumio[6889]: info: Listing playlists Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 18:28:45 motivo volumio[6889]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 13 18:28:45 motivo volumio[6889]: info: Received Get System Info Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 18:28:45 motivo volumio[6889]: info: Discovery: Getting this device information Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::servicePushState Apr 13 18:28:45 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:45 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: verbose: STATE SERVICE {"status":"stop","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":false,"channels":2} Apr 13 18:28:45 motivo volumio[6889]: verbose: CURRENT POSITION 0 Apr 13 18:28:45 motivo volumio[6889]: info: CoreStateMachine::syncState stateService stop Apr 13 18:28:45 motivo volumio[6889]: info: CoreStateMachine::syncState currentStatus stop Apr 13 18:28:45 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:45 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:45 motivo volumio[6889]: info: No code Apr 13 18:28:45 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:45 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:45 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:45 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::volumiosetSourceActiveno-source Apr 13 18:28:45 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 18:28:45 motivo volumio[6889]: Cannot find translation for source Music Server Apr 13 18:28:45 motivo volumio[6889]: Cannot find translation for source Podcast Apr 13 18:28:45 motivo volumio[6889]: info: MCU Signalled Playback Inactive Apr 13 18:28:45 motivo sudo[7200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 13 18:28:45 motivo sudo[7200]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:45 motivo sudo[7200]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:45 motivo volumio[6889]: info: Upmpdcli Daemon Started Apr 13 18:28:45 motivo sudo[7204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 13 18:28:45 motivo sudo[7204]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:45 motivo sudo[7204]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:45 motivo volumio[6889]: info: Volumio BT Module successfully started Apr 13 18:28:46 motivo volumio[6889]: info: TidalConnect service stoped! Apr 13 18:28:46 motivo volumio[6889]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 13 18:28:46 motivo volumio[6889]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 13 18:28:46 motivo sudo[7215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 13 18:28:46 motivo sudo[7215]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:46 motivo systemd[1]: Started Volumio Tidal Connect Service. Apr 13 18:28:46 motivo sudo[7215]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:46 motivo volumio[6889]: info: Initializing I2S Bus Apr 13 18:28:46 motivo volumio[6889]: info: Executing endpoint tc_getconfig Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 13 18:28:46 motivo vtcs[7217]: STARTING TidalConnect services, version: 1.6.1 Apr 13 18:28:46 motivo vtcs[7217]: STARTED TidalConnect services. Apr 13 18:28:46 motivo volumio[6889]: info: Executing endpoint tc_connect Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 13 18:28:46 motivo volumio[6889]: info: Connecting to TidalConnect Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::servicePushState Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: 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} Apr 13 18:28:46 motivo volumio[6889]: verbose: CURRENT POSITION 0 Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::syncState stateService stop Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::syncState currentStatus stop Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:46 motivo volumio[6889]: info: No code Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::servicePushState Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: 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} Apr 13 18:28:46 motivo volumio[6889]: verbose: CURRENT POSITION 0 Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::syncState stateService stop Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::syncState currentStatus stop Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:46 motivo volumio[6889]: info: No code Apr 13 18:28:46 motivo volumio[6889]: info: CoreStateMachine::pushState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioPushState Apr 13 18:28:46 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:28:46 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output update for this device Apr 13 18:28:46 motivo volumio[6889]: info: MRS: Pushing multiroomSync output Apr 13 18:28:47 motivo sudo[7280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=massi,password=M5$HRzzEG7hmLKtrb#QB,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.2/music /mnt/NAS/NAS Apr 13 18:28:47 motivo sudo[7280]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:47 motivo kernel: CIFS: Attempting to mount //192.168.0.2/music Apr 13 18:28:47 motivo volumio[6889]: info: Successfully initialized Motivo I2S Bus Apr 13 18:28:48 motivo volumio[6889]: info: MRS: Getting audio outputs on start Apr 13 18:28:48 motivo volumio[6889]: info: MRS: Requesting all other devices output Apr 13 18:28:49 motivo volumio-remote-updater[715]: [2026-04-13 18:28:49] [connect] Successful connection Apr 13 18:28:49 motivo volumio-remote-updater[715]: [2026-04-13 18:28:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1776097729 101 Apr 13 18:28:49 motivo volumio[6889]: 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: 7 Apr 13 18:28:49 motivo volumio[6889]: info: TidalConnect service started! Apr 13 18:28:49 motivo volumio[6889]: info: Completed starting Core Plugins Apr 13 18:28:49 motivo volumio[6889]: info: ------------------------------------------- Apr 13 18:28:49 motivo volumio[6889]: info: ----- MyVolumio plugins startup ---- Apr 13 18:28:49 motivo volumio[6889]: info: ------------------------------------------- Apr 13 18:28:49 motivo volumio[6889]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 13 18:28:49 motivo volumio[6889]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 13 18:28:53 motivo sudo[7280]: pam_unix(sudo:session): session closed for user root Apr 13 18:28:53 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 13 18:28:53 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Apr 13 18:28:53 motivo volumio[6889]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Apr 13 18:28:53 motivo volumio[6889]: info: Checking for updated MCU Firmware Apr 13 18:28:53 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 18:28:53 motivo volumio[6889]: info: Firware on device is on latest version, no need to update Apr 13 18:28:54 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: musicserver , handleBrowseUri Apr 13 18:28:55 motivo volumio[6889]: info: Preload queue cleared Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 13 18:28:58 motivo volumio[6889]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 13 18:28:58 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:58 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:28:58 motivo volumio[6889]: info: Starting MyVolumio Remote Streaming Endpoints Apr 13 18:28:58 motivo volumio[6889]: info: MyVolumio login type: Token Apr 13 18:28:59 motivo volumio[6889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 13 18:28:59 motivo volumio[6889]: 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' Apr 13 18:28:59 motivo sudo[7303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=massi,password=M5$HRzzEG7hmLKtrb#QB,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.2/music /mnt/NAS/NAS Apr 13 18:28:59 motivo sudo[7303]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:28:59 motivo kernel: CIFS: Attempting to mount //192.168.0.2/music Apr 13 18:28:59 motivo volumio[6889]: Searching plugin music_service/musicserver Apr 13 18:28:59 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: musicserver , search Apr 13 18:28:59 motivo volumio[6889]: [{"title":"Artists from Music Server","availableListViews":["list","grid"],"items":[{"service":"musicserver","type":"artist","title":"Green Day","uri":"filesystem-music-source://artists/0b68682e-741e-404a-a00c-7a909dc9c1bd"},{"service":"musicserver","type":"artist","title":"Original Broadway Cast Featuring Green Day","uri":"filesystem-music-source://artists/0854425e-1a9c-4c5d-91f3-de8c1d145f5c"},{"service":"musicserver","type":"artist","title":"U2 And Green Day","uri":"filesystem-music-source://artists/6c743406-a392-4448-9bc3-bef709448707"}]},{"title":"Albums from Music Server","availableListViews":["list","grid"],"items":[{"service":"musicserver","type":"album","title":"Greatest Hits: God’s Favorite Band","uri":"filesystem-music-source://albums/db0b95c9-cc26-4a15-a522-3e39190cdc67"},{"service":"musicserver","type":"album","title":"Vitamin String Quartet Performs Green Day's American Idiot","uri":"filesystem-music-source://albums/edb4d9b7-7721-434b-87e9-b5d265c3ee8d"}]},{"title":"Songs from Music Server","availableListViews":["list"],"items":[{"service":"musicserver","type":"song","artist":"","album":"Pulse [Disc 1]","uri":"filesystem-music-source://songs/ac19392b-27f3-46a9-8465-9efce3db40e2"},{"service":"musicserver","type":"song","artist":"Original Broadway Cast Featuring Green Day","album":"American Idiot: The Original Broadway Cast Recording (Cd2)","uri":"filesystem-music-source://songs/c0e44518-c583-4281-b98a-94cde4b2e75e"},{"service":"musicserver","type":"song","artist":"Green Day","album":"21st Century Breakdown","uri":"filesystem-music-source://songs/5696ca25-997f-4daf-8b08-b7434955de3c"},{"service":"musicserver","type":"song","artist":"Green Day","album":"1,039 / Smoothed Out Out Slappy Hours","uri":"filesystem-music-source://songs/f45f374c-5597-49ef-90a7-144aba3d123e"}]}] Apr 13 18:28:59 motivo volumio[6889]: info: All search sources collected, pushing search results Apr 13 18:28:59 motivo volumio[6889]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 13 18:28:59 motivo volumio[6889]: Searching plugin music_service/musicserver Apr 13 18:28:59 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: musicserver , search Apr 13 18:28:59 motivo volumio[6889]: [{"title":"Songs from Music Server","availableListViews":["list"],"items":[{"service":"musicserver","type":"song","artist":"Green Day","album":"21st Century Breakdown","uri":"filesystem-music-source://songs/5696ca25-997f-4daf-8b08-b7434955de3c"}]}] Apr 13 18:28:59 motivo volumio[6889]: info: All search sources collected, pushing search results Apr 13 18:29:00 motivo volumio[6889]: info: MyVolumio token set successfully Apr 13 18:29:00 motivo volumio[6889]: info: MYVOLUMIO: Adding device Apr 13 18:29:00 motivo volumio[6889]: info: MYVOLUMIO: Evaluating Server Apr 13 18:29:00 motivo volumio[6889]: info: MyVolumio Plan changed: superstar Apr 13 18:29:00 motivo volumio[6889]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 13 18:29:00 motivo volumio[6889]: info: Adding audio output: Apr 13 18:29:00 motivo volumio[6889]: info: MYVOLUMIO: Adding device Apr 13 18:29:00 motivo volumio[6889]: info: MYVOLUMIO: Evaluating Server Apr 13 18:29:00 motivo volumio[6889]: info: Remote config written successfully Apr 13 18:29:00 motivo volumio[6889]: info: Starting Tunnel 1 Apr 13 18:29:00 motivo volumio[6889]: info: Starting Tunnel Connection Checker Apr 13 18:29:01 motivo volumio[6889]: info: MYVolumio Device enabled Apr 13 18:29:01 motivo volumio[6889]: info: MyVolumio status changed Apr 13 18:29:01 motivo volumio[6889]: info: Streaming services startup Apr 13 18:29:01 motivo volumio[6889]: info: Starting Streaming Daemon Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 13 18:29:01 motivo sudo[7346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 13 18:29:01 motivo sudo[7346]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:29:01 motivo volumio[6889]: info: Setting Geolocation for MyVolumio to eu10 Apr 13 18:29:01 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:01 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:01 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:01 motivo sudo[7346]: pam_unix(sudo:session): session closed for user root Apr 13 18:29:01 motivo volumio[6889]: error: Cannot start Volumio Streaming Daemon Apr 13 18:29:01 motivo volumio[6889]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 13 18:29:01 motivo volumio[6889]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 13 18:29:01 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Apr 13 18:29:01 motivo volumio[6889]: info: Setting Geolocation for MyVolumio to eu7 Apr 13 18:29:01 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:01 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:01 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:01 motivo volumio[6889]: info: Successfully Added MyVolumio device Apr 13 18:29:02 motivo volumio[6889]: info: Successfully Added MyVolumio device Apr 13 18:29:02 motivo volumio[6889]: info: Updating MyVolumio device info Apr 13 18:29:02 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:02 motivo volumio[6889]: info: Updating MyVolumio device info Apr 13 18:29:02 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:02 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:02 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:02 motivo volumio[6889]: Searching plugin music_service/musicserver Apr 13 18:29:02 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: musicserver , search Apr 13 18:29:02 motivo volumio[6889]: info: Successfully Updated MyVolumio device Apr 13 18:29:02 motivo volumio[6889]: [{"title":"Artists from Music Server","availableListViews":["list","grid"],"items":[{"service":"musicserver","type":"artist","title":"Green Day","uri":"filesystem-music-source://artists/0b68682e-741e-404a-a00c-7a909dc9c1bd"},{"service":"musicserver","type":"artist","title":"Original Broadway Cast Featuring Green Day","uri":"filesystem-music-source://artists/0854425e-1a9c-4c5d-91f3-de8c1d145f5c"},{"service":"musicserver","type":"artist","title":"U2 And Green Day","uri":"filesystem-music-source://artists/6c743406-a392-4448-9bc3-bef709448707"}]},{"title":"Albums from Music Server","availableListViews":["list","grid"],"items":[{"service":"musicserver","type":"album","title":"Greatest Hits: God’s Favorite Band","uri":"filesystem-music-source://albums/db0b95c9-cc26-4a15-a522-3e39190cdc67"},{"service":"musicserver","type":"album","title":"Vitamin String Quartet Performs Green Day's American Idiot","uri":"filesystem-music-source://albums/edb4d9b7-7721-434b-87e9-b5d265c3ee8d"}]},{"title":"Songs from Music Server","availableListViews":["list"],"items":[{"service":"musicserver","type":"song","artist":"","album":"Pulse [Disc 1]","uri":"filesystem-music-source://songs/ac19392b-27f3-46a9-8465-9efce3db40e2"},{"service":"musicserver","type":"song","artist":"Original Broadway Cast Featuring Green Day","album":"American Idiot: The Original Broadway Cast Recording (Cd2)","uri":"filesystem-music-source://songs/c0e44518-c583-4281-b98a-94cde4b2e75e"},{"service":"musicserver","type":"song","artist":"Green Day","album":"21st Century Breakdown","uri":"filesystem-music-source://songs/5696ca25-997f-4daf-8b08-b7434955de3c"},{"service":"musicserver","type":"song","artist":"Green Day","album":"1,039 / Smoothed Out Out Slappy Hours","uri":"filesystem-music-source://songs/f45f374c-5597-49ef-90a7-144aba3d123e"}]}] Apr 13 18:29:02 motivo volumio[6889]: info: All search sources collected, pushing search results Apr 13 18:29:03 motivo volumio[6889]: info: Successfully Updated MyVolumio device Apr 13 18:29:04 motivo volumio[6889]: Searching plugin music_service/musicserver Apr 13 18:29:04 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: musicserver , search Apr 13 18:29:04 motivo volumio[6889]: [{"title":"Artists from Music Server","availableListViews":["list","grid"],"items":[{"service":"musicserver","type":"artist","title":"Green Day","uri":"filesystem-music-source://artists/0b68682e-741e-404a-a00c-7a909dc9c1bd"},{"service":"musicserver","type":"artist","title":"Original Broadway Cast Featuring Green Day","uri":"filesystem-music-source://artists/0854425e-1a9c-4c5d-91f3-de8c1d145f5c"},{"service":"musicserver","type":"artist","title":"U2 And Green Day","uri":"filesystem-music-source://artists/6c743406-a392-4448-9bc3-bef709448707"}]},{"title":"Albums from Music Server","availableListViews":["list","grid"],"items":[{"service":"musicserver","type":"album","title":"Vitamin String Quartet Performs Green Day's American Idiot","uri":"filesystem-music-source://albums/edb4d9b7-7721-434b-87e9-b5d265c3ee8d"}]},{"title":"Songs from Music Server","availableListViews":["list"],"items":[{"service":"musicserver","type":"song","artist":"Original Broadway Cast Featuring Green Day","album":"American Idiot: The Original Broadway Cast Recording (Cd2)","uri":"filesystem-music-source://songs/c0e44518-c583-4281-b98a-94cde4b2e75e"},{"service":"musicserver","type":"song","artist":"Green Day","album":"1,039 / Smoothed Out Out Slappy Hours","uri":"filesystem-music-source://songs/f45f374c-5597-49ef-90a7-144aba3d123e"}]}] Apr 13 18:29:04 motivo volumio[6889]: info: All search sources collected, pushing search results Apr 13 18:29:05 motivo sudo[7303]: pam_unix(sudo:session): session closed for user root Apr 13 18:29:05 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 13 18:29:05 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Apr 13 18:29:05 motivo volumio[6889]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Apr 13 18:29:06 motivo sudo[7372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 13 18:29:06 motivo sudo[7372]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:29:06 motivo autossh[6562]: received signal to exit (15) Apr 13 18:29:06 motivo systemd[1]: Stopping MyVolumio SSH Tunnel... Apr 13 18:29:06 motivo systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Apr 13 18:29:06 motivo systemd[1]: sshtunnel.service: Succeeded. Apr 13 18:29:06 motivo systemd[1]: Stopped MyVolumio SSH Tunnel. Apr 13 18:29:06 motivo volumio[6889]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 13 18:29:06 motivo volumio[6889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 13 18:29:06 motivo systemd[1]: Started MyVolumio SSH Tunnel. Apr 13 18:29:06 motivo sudo[7372]: pam_unix(sudo:session): session closed for user root Apr 13 18:29:06 motivo volumio[6889]: info: Remote SSH Started Apr 13 18:29:06 motivo autossh[7375]: port set to 0, monitoring disabled Apr 13 18:29:06 motivo autossh[7375]: starting ssh (count 1) Apr 13 18:29:06 motivo autossh[7375]: ssh child pid is 7378 Apr 13 18:29:06 motivo volumio[6889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 13 18:29:06 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:29:06 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:29:06 motivo volumiossh-tunnel[7374]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Apr 13 18:29:09 motivo volumio[6889]: error: MyVolumio Plugin failed to start in a timely fashion Apr 13 18:29:09 motivo volumio[6889]: [Metrics] CommandRouter: 36s 284.32ms Apr 13 18:29:09 motivo volumio[6889]: info: CoreCommandRouter::volumiosetStartupVolume Apr 13 18:29:09 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:29:09 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:09 motivo volumio[6889]: info: CoreCommandRouter::Close All Modals sent Apr 13 18:29:09 motivo volumio[6889]: info: CoreCommandRouter::Close All Modals sent Apr 13 18:29:10 motivo sudo[7382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=massi,password=M5$HRzzEG7hmLKtrb#QB,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.2/music /mnt/NAS/NAS Apr 13 18:29:10 motivo sudo[7382]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:29:10 motivo kernel: CIFS: Attempting to mount //192.168.0.2/music Apr 13 18:29:10 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 13 18:29:10 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 13 18:29:10 motivo volumio[6889]: No protocol specified Apr 13 18:29:10 motivo volumio[6889]: xcb_connection_has_error() returned true Apr 13 18:29:10 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 13 18:29:13 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 18:29:13 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 13 18:29:13 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 18:29:13 motivo volumio-remote-updater[715]: No test mode Apr 13 18:29:13 motivo volumio-remote-updater[715]: No alpha test mode Apr 13 18:29:13 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 13 18:29:13 motivo volumio[6889]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix for BLE notification issues
    • \n
  • Fix for mDNS/BCT
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Update Tidal SDK
    • \n
  • Update Qobuz Connect SDK
    • \n
\n","title":"Update v3.912","updateavailable":true} Apr 13 18:29:13 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 13 18:29:13 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 18:29:13 motivo volumio[6889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 13 18:29:14 motivo volumio[6889]: info: CoreCommandRouter::volumioGetState Apr 13 18:29:14 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:29:16 motivo sudo[7382]: pam_unix(sudo:session): session closed for user root Apr 13 18:29:16 motivo kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Apr 13 18:29:16 motivo kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Apr 13 18:29:16 motivo volumio[6889]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Apr 13 18:29:16 motivo volumio[6889]: info: BOOT COMPLETED Apr 13 18:29:17 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:29:17 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:29:17 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:29:17 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:29:17 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 13 18:29:17 motivo volumio[6889]: info: Not Reporting Auto name since its the default one Apr 13 18:29:17 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 13 18:29:17 motivo volumio[6889]: No protocol specified Apr 13 18:29:17 motivo volumio[6889]: xcb_connection_has_error() returned true Apr 13 18:29:17 motivo sudo[7414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 13 18:29:17 motivo sudo[7414]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:29:17 motivo sudo[7414]: pam_unix(sudo:session): session closed for user root Apr 13 18:29:17 motivo sudo[7416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 13 18:29:17 motivo sudo[7416]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 13 18:29:17 motivo systemd[1]: Stopping RAAT DAEMON... Apr 13 18:29:17 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 13 18:29:17 motivo systemd[1]: raat-daemon.service: Succeeded. Apr 13 18:29:17 motivo systemd[1]: Stopped RAAT DAEMON. Apr 13 18:29:17 motivo volumio[6889]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 13 18:29:17 motivo volumio[6889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 13 18:29:17 motivo systemd[1]: Started RAAT DAEMON. Apr 13 18:29:17 motivo sudo[7416]: pam_unix(sudo:session): session closed for user root Apr 13 18:29:17 motivo volumio[6889]: info: Raat Daemon started successfully Apr 13 18:29:17 motivo volumio[6889]: info: Executing endpoint restartRAATSocket Apr 13 18:29:17 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 13 18:29:17 motivo volumio[6889]: info: CorePlayQueue::getTrack 0 Apr 13 18:29:17 motivo volumio[6889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 13 18:29:18 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:29:18 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 13 18:29:18 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 13 18:29:18 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 13 18:29:18 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 13 18:29:18 motivo volumio[6889]: info: CoreCommandRouter::volumioGetBrowseSources Apr 13 18:29:18 motivo volumio[6889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 18:29:20 motivo volumio[6889]: info: Preload queue cleared Apr 13 18:29:20 motivo volumio[6889]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 13 18:29:20 motivo volumio[6889]: info: CoreStateMachine::ClearQueue Apr 13 18:29:20 motivo volumio[6889]: info: CoreStateMachine::stop Apr 13 18:29:20 motivo volumio[6889]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 13 18:29:20 motivo volumio[6889]: info: CorePlayQueue::clearPlayQueue Apr 13 18:29:20 motivo volumio[6889]: info: CorePlayQueue::saveQueue Apr 13 18:29:20 motivo volumio[6889]: info: CoreCommandRouter::volumioPushQueue Apr 13 18:29:20 motivo volumio[6889]: info: CoreStateMachine::addQueueItems Apr 13 18:29:20 motivo volumio[6889]: info: CorePlayQueue::addQueueItems Apr 13 18:29:20 motivo volumio[6889]: info: Preload queue cleared Apr 13 18:29:20 motivo volumio[6889]: info: Adding Item to queue: filesystem-music-source://songs/c0e44518-c583-4281-b98a-94cde4b2e75e Apr 13 18:29:20 motivo volumio[6889]: info: Exploding uri filesystem-music-source://songs/c0e44518-c583-4281-b98a-94cde4b2e75e in service musicserver Apr 13 18:29:20 motivo volumio[6889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 18:29:20 motivo volumio[6889]: ReferenceError: curUri is not defined Apr 13 18:29:20 motivo volumio[6889]: at MusicServerPlugin.#checkUriAndExecute (/data/plugins/music_service/musicserver/index.js:69:58) Apr 13 18:29:20 motivo volumio[6889]: at MusicServerPlugin.explodeUri (/data/plugins/music_service/musicserver/index.js:64:36) Apr 13 18:29:20 motivo volumio[6889]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Apr 13 18:29:20 motivo volumio[6889]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 13 18:29:20 motivo volumio[6889]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 13 18:29:20 motivo volumio[6889]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Apr 13 18:29:20 motivo volumio[6889]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Apr 13 18:29:20 motivo volumio[6889]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Apr 13 18:29:20 motivo volumio[6889]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Apr 13 18:29:20 motivo volumio[6889]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Apr 13 18:29:20 motivo volumio[6889]: at Socket.emit (events.js:315:20) Apr 13 18:29:20 motivo volumio[6889]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 13 18:29:20 motivo volumio[6889]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 13 18:29:20 motivo volumio[6889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 18:29:20 motivo sudo[7442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-13 18:28 Apr 13 18:29:20 motivo sudo[7442]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 03:22:29 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="7f33e77759202d530afe461231bb6a6d"