-- Logs begin at Sun 2025-07-06 15:22:47 CEST, end at Sun 2025-07-06 15:25:06 CEST. -- Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 15:24:00 smsl-n100 systemd[1]: Starting Bluetooth service... Jul 06 15:24:00 smsl-n100 volumio[1113]: info: VolumeController::SetAlsaVolume0 Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CoreStateMachine::pushState Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 06 15:24:00 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output update for this device Jul 06 15:24:00 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output Jul 06 15:24:00 smsl-n100 pulseaudio[1503]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 06 15:24:00 smsl-n100 pulseaudio[1503]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Applying permissions Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Setting BT discoverable and pairable Jul 06 15:24:00 smsl-n100 bluetoothd[1534]: Bluetooth daemon 5.50 Jul 06 15:24:00 smsl-n100 bluetoothd[1534]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 06 15:24:00 smsl-n100 dbus-daemon[544]: [system] Successfully activated service 'org.bluez' Jul 06 15:24:00 smsl-n100 systemd[1]: Started Bluetooth service. Jul 06 15:24:00 smsl-n100 bluetoothd[1534]: Starting SDP server Jul 06 15:24:00 smsl-n100 bluetoothd[1534]: Excluding (cli) sap Jul 06 15:24:00 smsl-n100 bluetoothd[1534]: kernel lacks bnep-protocol support Jul 06 15:24:00 smsl-n100 bluetoothd[1534]: System does not support network plugin Jul 06 15:24:00 smsl-n100 dbus-daemon[544]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.40' (uid=0 pid=1534 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jul 06 15:24:00 smsl-n100 bluetoothd[1534]: Bluetooth management interface 1.14 initialized Jul 06 15:24:00 smsl-n100 pulseaudio[1503]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Agent registered Jul 06 15:24:00 smsl-n100 volumiobt[1474]: [bluetooth]# -e power on Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Invalid command in menu main: -e Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Use "help" for a list of available commands in a menu. Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Use "menu " if you want to enter any submenu. Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Use "back" if you want to return to menu main. Jul 06 15:24:00 smsl-n100 volumiobt[1474]: [bluetooth]# agent on Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Agent is already registered Jul 06 15:24:00 smsl-n100 volumiobt[1474]: [bluetooth]# discoverable on Jul 06 15:24:00 smsl-n100 volumiobt[1474]: No default controller available Jul 06 15:24:00 smsl-n100 volumiobt[1474]: [bluetooth]# pairable on Jul 06 15:24:00 smsl-n100 volumiobt[1474]: No default controller available Jul 06 15:24:00 smsl-n100 volumiobt[1474]: [bluetooth]# agent NoInputNoOutput Jul 06 15:24:00 smsl-n100 volumiobt[1474]: Agent is already registered Jul 06 15:24:00 smsl-n100 volumiobt[1474]: [bluetooth]# default-agent Jul 06 15:24:00 smsl-n100 volumiobt[1474]: [bluetooth]# quit Jul 06 15:24:00 smsl-n100 volumio[1113]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 06 15:24:00 smsl-n100 volumio[1113]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 06 15:24:00 smsl-n100 systemd[1]: Starting Hostname Service... Jul 06 15:24:00 smsl-n100 volumiobt[1474]: [59B blob data] Jul 06 15:24:00 smsl-n100 volumio[1113]: info: MRS: Getting audio outputs on start Jul 06 15:24:00 smsl-n100 volumio[1113]: info: MRS: Requesting all other devices output Jul 06 15:24:00 smsl-n100 sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 06 15:24:00 smsl-n100 sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 15:24:00 smsl-n100 volumio[1113]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 06 15:24:00 smsl-n100 volumio[1113]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 06 15:24:00 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Jul 06 15:24:00 smsl-n100 sudo[1541]: pam_unix(sudo:session): session closed for user root Jul 06 15:24:00 smsl-n100 dbus-daemon[544]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 06 15:24:00 smsl-n100 systemd[1]: Started Hostname Service. Jul 06 15:24:00 smsl-n100 volumio[1113]: error: MPD error: The expression evaluated to a falsy value: Jul 06 15:24:00 smsl-n100 volumio[1113]: assert.ok(self.idling) Jul 06 15:24:00 smsl-n100 volumio[1113]: error: The expression evaluated to a falsy value: Jul 06 15:24:00 smsl-n100 volumio[1113]: assert.ok(self.idling) Jul 06 15:24:00 smsl-n100 volumio[1113]: info: MPD running with PID1411 Jul 06 15:24:00 smsl-n100 volumio[1113]: ,establishing connection Jul 06 15:24:00 smsl-n100 volumio[1113]: error: updateQueue error: null Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CoreStateMachine::pushState Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 15:24:00 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 06 15:24:00 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output update for this device Jul 06 15:24:00 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output Jul 06 15:24:00 smsl-n100 volumio[1113]: error: Help! Some callbacks for volumioPushState are crashing! Jul 06 15:24:00 smsl-n100 volumio[1113]: error: Cannot read property 'sendVolumeMute' of undefined Jul 06 15:24:00 smsl-n100 volumio[1113]: error: Serial API: Failed to decode command: 100, message: undefined Jul 06 15:24:00 smsl-n100 volumio[1113]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 06 15:24:00 smsl-n100 volumio[1113]: error: Serial API: Failed to decode command: 100, message: undefined Jul 06 15:24:00 smsl-n100 volumio[1113]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 06 15:24:00 smsl-n100 volumio[1113]: error: updateQueue error: null Jul 06 15:24:01 smsl-n100 volumio[1369]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Successfully retrieved User Subscription From TIDAL Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Adding TIDAL to Browse Sources Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 15:24:01 smsl-n100 volumio[1113]: info: [1751808241182] CoreMusicLibrary::Adding element TIDAL Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 15:24:01 smsl-n100 volumio[1113]: Cannot find translation for source TIDAL Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Stopping AccessToken refresher cron Jul 06 15:24:01 smsl-n100 volumio[1113]: info: AccessToken refresher cron started Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Adding TIDAL REST API Endpoints Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Executing endpoint tc_getconfig Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 06 15:24:01 smsl-n100 vtcs[1543]: STARTING TidalConnect services, version: 1.4.0.34 Jul 06 15:24:01 smsl-n100 volumio[1113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 15:24:01 smsl-n100 vtcs[1543]: STARTED TidalConnect services. Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Executing endpoint tc_connect Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Connecting to TidalConnect Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::servicePushState Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreStateMachine::pushState Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 06 15:24:01 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output update for this device Jul 06 15:24:01 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::servicePushState Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreStateMachine::pushState Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 06 15:24:01 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output update for this device Jul 06 15:24:01 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output Jul 06 15:24:01 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:01 smsl-n100 volumio[1113]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jul 06 15:24:03 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 15:24:03 smsl-n100 volumio[1113]: info: TidalConnect service started! Jul 06 15:24:03 smsl-n100 volumio[1113]: info: Completed starting Core Plugins Jul 06 15:24:03 smsl-n100 volumio[1113]: info: ------------------------------------------- Jul 06 15:24:03 smsl-n100 volumio[1113]: info: ----- MyVolumio plugins startup ---- Jul 06 15:24:03 smsl-n100 volumio[1113]: info: ------------------------------------------- Jul 06 15:24:03 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 06 15:24:03 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 15:24:03 smsl-n100 volumio[1113]: info: VolumeController::SetAlsaVolume0 Jul 06 15:24:03 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 15:24:03 smsl-n100 volumio[1113]: info: CoreStateMachine::pushState Jul 06 15:24:03 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:03 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 15:24:03 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 06 15:24:03 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output update for this device Jul 06 15:24:03 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output Jul 06 15:24:10 smsl-n100 volumio[1369]: ............................................................................................................................++++ Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 06 15:24:12 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 06 15:24:13 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 06 15:24:13 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 06 15:24:13 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:13 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:13 smsl-n100 volumio[1113]: info: Starting MyVolumio Remote Streaming Endpoints Jul 06 15:24:14 smsl-n100 volumio[1113]: info: MyVolumio login type: Token Jul 06 15:24:14 smsl-n100 volumio[1113]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 06 15:24:14 smsl-n100 volumio[1113]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jul 06 15:24:14 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 15:24:14 smsl-n100 volumio[1113]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 06 15:24:15 smsl-n100 volumio[1113]: info: MyVolumio token set successfully Jul 06 15:24:15 smsl-n100 volumio[1113]: info: MYVOLUMIO: Adding device Jul 06 15:24:15 smsl-n100 volumio[1113]: info: MYVOLUMIO: Evaluating Server Jul 06 15:24:16 smsl-n100 volumio[1369]: ..............................................................................++++ Jul 06 15:24:16 smsl-n100 volumio[1369]: e is 65537 (0x010001) Jul 06 15:24:16 smsl-n100 volumio[1369]: writing RSA key Jul 06 15:24:16 smsl-n100 volumio[1113]: info: MyVolumio status changed Jul 06 15:24:16 smsl-n100 volumio[1113]: info: Streaming services startup Jul 06 15:24:16 smsl-n100 volumio[1113]: info: Starting Streaming Daemon Jul 06 15:24:16 smsl-n100 volumio[1113]: info: Removing browser output: myVolumio user plan is not superstar Jul 06 15:24:16 smsl-n100 volumio[1113]: info: Removing audio output: Jul 06 15:24:16 smsl-n100 volumio[1113]: info: Stoppping Tunnel 1 Jul 06 15:24:16 smsl-n100 sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 06 15:24:16 smsl-n100 sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 15:24:16 smsl-n100 sudo[1641]: pam_unix(sudo:session): session closed for user root Jul 06 15:24:16 smsl-n100 sudo[1644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 06 15:24:16 smsl-n100 sudo[1644]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 15:24:16 smsl-n100 volumio[1113]: error: Cannot start Volumio Streaming Daemon Jul 06 15:24:16 smsl-n100 volumio[1113]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 06 15:24:16 smsl-n100 volumio[1113]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 06 15:24:16 smsl-n100 sudo[1644]: pam_unix(sudo:session): session closed for user root Jul 06 15:24:16 smsl-n100 volumio[1113]: info: Remote SSH Stopped Jul 06 15:24:16 smsl-n100 volumio[1113]: info: Setting Geolocation for MyVolumio to eu6 Jul 06 15:24:16 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:16 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:16 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:17 smsl-n100 volumio[1113]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 06 15:24:17 smsl-n100 volumio[1113]: info: Updating MyVolumio device info Jul 06 15:24:17 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:18 smsl-n100 volumio[1113]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 06 15:24:21 smsl-n100 volumio[1113]: info: MYVOLUMIO: Adding device Jul 06 15:24:21 smsl-n100 volumio[1113]: info: MYVOLUMIO: Evaluating Server Jul 06 15:24:22 smsl-n100 volumio[1113]: info: Setting Geolocation for MyVolumio to eu6 Jul 06 15:24:22 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:22 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:22 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:23 smsl-n100 volumio[1113]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 06 15:24:23 smsl-n100 volumio[1113]: error: MyVolumio Plugin failed to start in a timely fashion Jul 06 15:24:23 smsl-n100 volumio[1113]: [Metrics] CommandRouter: 64s 824.39ms Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumiosetStartupVolume Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::Close All Modals sent Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::Close All Modals sent Jul 06 15:24:23 smsl-n100 volumio[1113]: info: Updating MyVolumio device info Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 15:24:23 smsl-n100 volumio[1113]: info: Discovery: Getting this device information Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:23 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 15:24:24 smsl-n100 volumio[1113]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 06 15:24:24 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 06 15:24:24 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 06 15:24:24 smsl-n100 volumio[1113]: xcb_connection_has_error() returned true Jul 06 15:24:24 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 06 15:24:24 smsl-n100 volumio[1113]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.20 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 06 15:24:25 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 15:24:25 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 15:24:25 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 15:24:25 smsl-n100 volumio-remote-updater[556]: No test mode Jul 06 15:24:25 smsl-n100 volumio-remote-updater[556]: No alpha test mode Jul 06 15:24:25 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 06 15:24:26 smsl-n100 volumio[1113]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 06 15:24:26 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 06 15:24:26 smsl-n100 volumio[1113]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.20 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jul 06 15:24:29 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 15:24:29 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 15:24:29 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 15:24:29 smsl-n100 volumio[1113]: info: Discovery: Getting this device information Jul 06 15:24:29 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 06 15:24:29 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:29 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 15:24:29 smsl-n100 volumio[1113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 06 15:24:29 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 06 15:24:29 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:30 smsl-n100 volumio[1113]: info: BOOT COMPLETED Jul 06 15:24:30 smsl-n100 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 15:24:32 smsl-n100 volumio[1113]: info: Not Reporting Auto name since its the default one Jul 06 15:24:32 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 06 15:24:32 smsl-n100 volumio[1113]: xcb_connection_has_error() returned true Jul 06 15:24:32 smsl-n100 sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 06 15:24:32 smsl-n100 sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 15:24:32 smsl-n100 sudo[1697]: pam_unix(sudo:session): session closed for user root Jul 06 15:24:32 smsl-n100 sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 06 15:24:32 smsl-n100 sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 15:24:32 smsl-n100 systemd[1]: Started RAAT DAEMON. Jul 06 15:24:32 smsl-n100 sudo[1699]: pam_unix(sudo:session): session closed for user root Jul 06 15:24:32 smsl-n100 volumio[1113]: info: Raat Daemon started successfully Jul 06 15:24:33 smsl-n100 volumio[1113]: info: Executing endpoint restartRAATSocket Jul 06 15:24:33 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 06 15:24:33 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:33 smsl-n100 volumio[1113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 06 15:24:34 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:34 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 15:24:34 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 06 15:24:34 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 06 15:24:34 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 06 15:24:34 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioGetBrowseSources Jul 06 15:24:34 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 15:24:35 smsl-n100 volumio[1113]: info: RAAT: Requesting Headphone Status Jul 06 15:24:35 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 06 15:24:35 smsl-n100 volumio[1113]: info: CoreStateMachine::pushState Jul 06 15:24:35 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:35 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 15:24:35 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 06 15:24:35 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output update for this device Jul 06 15:24:35 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output Jul 06 15:24:35 smsl-n100 volumio[1113]: error: Serial API: Failed to decode command: 100, message: undefined Jul 06 15:24:35 smsl-n100 volumio[1113]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 06 15:24:35 smsl-n100 volumio[1113]: error: Serial API: Failed to decode command: 100, message: undefined Jul 06 15:24:35 smsl-n100 volumio[1113]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 06 15:24:36 smsl-n100 volumio[1113]: info: VolumeController::SetAlsaVolume0 Jul 06 15:24:37 smsl-n100 volumio[1113]: info: CoreStateMachine::pushState Jul 06 15:24:37 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:37 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 15:24:37 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 06 15:24:37 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output update for this device Jul 06 15:24:37 smsl-n100 volumio[1113]: info: MRS: Pushing multiroomSync output Jul 06 15:24:37 smsl-n100 volumio[1113]: info: Updating RAAT Signal Path Jul 06 15:24:54 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 15:24:54 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 15:24:54 smsl-n100 volumio[1113]: info: Discovery: Getting this device information Jul 06 15:24:54 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 06 15:24:54 smsl-n100 volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 06 15:24:54 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 15:24:56 smsl-n100 volumio[1113]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.20 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jul 06 15:24:57 smsl-n100 volumio[1113]: verbose: New Socket.io Connection to 192.168.0.8:3000 from 192.168.0.20 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jul 06 15:25:00 smsl-n100 volumio[1113]: info: CoreCommandRouter::volumioGetQueue Jul 06 15:25:00 smsl-n100 volumio[1113]: info: CoreStateMachine::getQueue Jul 06 15:25:00 smsl-n100 volumio[1113]: info: CorePlayQueue::getQueue Jul 06 15:25:05 smsl-n100 volumio[1113]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 06 15:25:05 smsl-n100 volumio[1113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 15:25:05 smsl-n100 volumio[1113]: TypeError: Cannot read property 'length' of undefined Jul 06 15:25:05 smsl-n100 volumio[1113]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 06 15:25:05 smsl-n100 volumio[1113]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 06 15:25:05 smsl-n100 volumio[1113]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 06 15:25:05 smsl-n100 volumio[1113]: at Parser.emit (events.js:400:28) Jul 06 15:25:05 smsl-n100 volumio[1113]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 06 15:25:05 smsl-n100 volumio[1113]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 06 15:25:05 smsl-n100 volumio[1113]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 06 15:25:05 smsl-n100 volumio[1113]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 06 15:25:05 smsl-n100 volumio[1113]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 06 15:25:05 smsl-n100 volumio[1113]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 06 15:25:05 smsl-n100 volumio[1113]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 06 15:25:05 smsl-n100 volumio[1113]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 06 15:25:05 smsl-n100 volumio[1113]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 06 15:25:05 smsl-n100 volumio[1113]: at IncomingMessage.emit (events.js:412:35) Jul 06 15:25:05 smsl-n100 volumio[1113]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 06 15:25:05 smsl-n100 volumio[1113]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 06 15:25:05 smsl-n100 volumio[1113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 15:25:06 smsl-n100 sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-06 15:24 Jul 06 15:25:06 smsl-n100 sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"