-- Logs begin at Fri 2025-08-29 19:13:24 -04, end at Fri 2025-08-29 19:27:08 -04. -- Aug 29 19:26:00 smsl-n100 sudo[6116]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 19:26:00 smsl-n100 sudo[6116]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:00 smsl-n100 sudo[6116]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Preparing CD Folders Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Adding CD REST API Endpoints Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Starting UDEV Watcher for CD Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Detecting CD presence with UDEV Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:26:00 smsl-n100 volumio[5846]: info: [1756509960136] CoreMusicLibrary::Adding element Last_100 Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Starting RAAT Plugin Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Additional UI Settings Added for plugin music_service/raat Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Registering DSP Elements listener and retrieving current ones Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Additional DSP elements updated Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Updating RAAT Signal Path Aug 29 19:26:00 smsl-n100 volumio[5846]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Streaming services startup Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Starting Streaming Daemon Aug 29 19:26:00 smsl-n100 sudo[6127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 29 19:26:00 smsl-n100 sudo[6127]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:00 smsl-n100 sudo[6127]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 19:26:00 smsl-n100 volumio[5846]: info: [1756509960412] CoreMusicLibrary::Adding element Webradio Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Initializing BBC Radios Aug 29 19:26:00 smsl-n100 sudo[6134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 19:26:00 smsl-n100 sudo[6134]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:00 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Aug 29 19:26:00 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 29 19:26:00 smsl-n100 systemd[1]: vtcs.service: Succeeded. Aug 29 19:26:00 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Aug 29 19:26:00 smsl-n100 sudo[6134]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 29 19:26:00 smsl-n100 volumio[5846]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 29 19:26:00 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:00 smsl-n100 volumio[5846]: error: Hi Res Audio Failed Login: Missing Login Data Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Initializing Serial Communication on port /dev/ttyS1 Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Touch Event Listener Process Starting Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Adding inputs REST Endpoints Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Scanning Audio Inputs Aug 29 19:26:00 smsl-n100 sudo[6151]: pam_unix(sudo:auth): conversation failed Aug 29 19:26:00 smsl-n100 sudo[6151]: pam_unix(sudo:auth): auth could not identify password for [volumio] Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Checking against Known Cards name Aug 29 19:26:00 smsl-n100 volumio[5846]: info: Adding Server instance for streaming Aug 29 19:26:01 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:26:01 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Initializing GPIOs Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Starting Button Listener Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Turning off Green LED Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Tuning on Blue LED Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Signaling System Ready Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Volumio Calling Home Aug 29 19:26:01 smsl-n100 volumio[5846]: (node:5846) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Aug 29 19:26:01 smsl-n100 volumio[5846]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Stopping AccessToken refresher cron for QOBUZ Aug 29 19:26:01 smsl-n100 volumio[5846]: info: AccessToken refresher cron started for QOBUZ Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Stopping AccessToken refresher cron Aug 29 19:26:01 smsl-n100 volumio[5846]: info: AccessToken refresher cron started Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Adding TIDAL REST API Endpoints Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Adding QOBUZ REST API Endpoints Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Discovery: adding c9c863b1-ae33-40a3-be33-1d000a6877c5 Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Discovery: Found device SMSL N100 Aug 29 19:26:01 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:01 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:01 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output for this device Aug 29 19:26:01 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Adding audio output: Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Adding audio output: Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Serial port opened successfully Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Sending serial start messages Aug 29 19:26:01 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:01 smsl-n100 volumio[5846]: info: Reporting MCU Network Status: 2 Aug 29 19:26:02 smsl-n100 volumio[5846]: info: Touch Event Listener Process Closed Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Cannot start Volumio Streaming Daemon Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 29 19:26:02 smsl-n100 volumio[5846]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100' Aug 29 19:26:02 smsl-n100 volumio[5846]: Can't get device info: No such device Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MPD Permissions set Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MPD Permissions set Aug 29 19:26:02 smsl-n100 volumio[5846]: info: Upmpdcli Daemon Started Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Aug 29 19:26:02 smsl-n100 volumio[5846]: info: Setting Device Volume Override Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 19:26:02 smsl-n100 volumio[5846]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 19:26:02 smsl-n100 volumio[5846]: info: Disabling external Volume Control Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 2, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 19:26:02 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:02 smsl-n100 volumio[5846]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 19:26:03 smsl-n100 volumio[5846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 29 19:26:03 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded. Aug 29 19:26:03 smsl-n100 sudo[6184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 29 19:26:03 smsl-n100 sudo[6184]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:03 smsl-n100 sudo[6184]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:03 smsl-n100 volumio[5846]: info: MRS: Found cast device: LSX-II-8a580bd6bec7f90c7826659fa45abd3c Aug 29 19:26:03 smsl-n100 volumio[5846]: info: Adding audio output: Aug 29 19:26:03 smsl-n100 volumio[5846]: info: TidalConnect service stoped! Aug 29 19:26:03 smsl-n100 volumio[5846]: info: Volumio BT Module successfully started Aug 29 19:26:03 smsl-n100 volumio[5846]: info: Starting Shairport Sync Aug 29 19:26:03 smsl-n100 volumio[5846]: info: Starting Shairport Sync Aug 29 19:26:03 smsl-n100 sudo[6194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:26:03 smsl-n100 sudo[6194]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:03 smsl-n100 volumio[5846]: info: Starting Shairport Sync Aug 29 19:26:03 smsl-n100 sudo[6197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:26:04 smsl-n100 sudo[6197]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:04 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:04 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:26:04 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:04 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:26:04 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:04 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:04 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 19:26:04 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:04 smsl-n100 volumio[5846]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:04 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:04 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:04 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:04 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 19:26:04 smsl-n100 sudo[6200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:26:04 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:04 smsl-n100 volumio[5846]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 19:26:04 smsl-n100 sudo[6200]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:04 smsl-n100 volumio[5846]: info: MRS: Found cast device: Beoconnect-Core-4653fc89ec3dea1f193ed452318557c7 Aug 29 19:26:04 smsl-n100 volumio[5846]: info: Adding audio output: Aug 29 19:26:04 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:26:04 smsl-n100 sudo[6197]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:04 smsl-n100 sudo[6194]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:04 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:26:04 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 29 19:26:04 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:26:04 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 29 19:26:04 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:26:04 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 29 19:26:04 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 29 19:26:04 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:26:04 smsl-n100 sudo[6200]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:04 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 29 19:26:04 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:04 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:04 smsl-n100 volumio[5846]: info: Shairport-Sync Started Aug 29 19:26:04 smsl-n100 volumio[5846]: Error adding Membership: Error: addMembership EINVAL Aug 29 19:26:04 smsl-n100 volumio[5846]: info: Shairport-Sync Started Aug 29 19:26:04 smsl-n100 volumio[5846]: info: Shairport-Sync Started Aug 29 19:26:05 smsl-n100 volumio[5846]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 29 19:26:05 smsl-n100 volumio[5846]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 29 19:26:05 smsl-n100 mpd[6125]: Aug 29 23:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 19:26:05 smsl-n100 systemd[1]: Started Music Player Daemon. Aug 29 19:26:05 smsl-n100 sudo[6109]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:05 smsl-n100 sudo[6098]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:05 smsl-n100 sudo[6222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 29 19:26:05 smsl-n100 sudo[6222]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:05 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Aug 29 19:26:05 smsl-n100 sudo[6222]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:05 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:05 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:05 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:05 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:05 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:05 smsl-n100 volumio[5846]: error: Help! Some callbacks for volumioPushState are crashing! Aug 29 19:26:05 smsl-n100 volumio[5846]: error: Cannot read property 'sendVolumeMute' of undefined Aug 29 19:26:05 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:05 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 19:26:05 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:05 smsl-n100 volumio[5846]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 19:26:05 smsl-n100 volumio[5846]: error: MPD error: The expression evaluated to a falsy value: Aug 29 19:26:05 smsl-n100 volumio[5846]: assert.ok(self.idling) Aug 29 19:26:05 smsl-n100 volumio[5846]: error: The expression evaluated to a falsy value: Aug 29 19:26:05 smsl-n100 volumio[5846]: assert.ok(self.idling) Aug 29 19:26:05 smsl-n100 volumio[5846]: info: MPD running with PID6125 Aug 29 19:26:05 smsl-n100 volumio[5846]: ,establishing connection Aug 29 19:26:05 smsl-n100 volumio[5846]: error: updateQueue error: null Aug 29 19:26:05 smsl-n100 volumio[5846]: error: updateQueue error: null Aug 29 19:26:05 smsl-n100 volumio[5846]: info: MRS: Getting audio outputs on start Aug 29 19:26:05 smsl-n100 volumio[5846]: info: MRS: Requesting all other devices output Aug 29 19:26:05 smsl-n100 volumio[5846]: info: Executing endpoint tc_getconfig Aug 29 19:26:05 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 29 19:26:05 smsl-n100 vtcs[6233]: STARTING TidalConnect services, version: 1.4.0.34 Aug 29 19:26:06 smsl-n100 vtcs[6233]: STARTED TidalConnect services. Aug 29 19:26:06 smsl-n100 volumio[5846]: info: Executing endpoint tc_connect Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 29 19:26:06 smsl-n100 volumio[5846]: info: Connecting to TidalConnect Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::servicePushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: 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} Aug 29 19:26:06 smsl-n100 volumio[5846]: verbose: CURRENT POSITION 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::syncState stateService stop Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::syncState currentStatus stop Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:06 smsl-n100 volumio[5846]: info: No code Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::servicePushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: 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} Aug 29 19:26:06 smsl-n100 volumio[5846]: verbose: CURRENT POSITION 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::syncState stateService stop Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::syncState currentStatus stop Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:06 smsl-n100 volumio[5846]: info: No code Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:06 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:06 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:06 smsl-n100 volumio[5846]: info: Volumio called home Aug 29 19:26:07 smsl-n100 volumio-remote-updater[5702]: [2025-08-29 23:26:07] [connect] Successful connection Aug 29 19:26:07 smsl-n100 volumio-remote-updater[5702]: [2025-08-29 23:26:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1756509967 101 Aug 29 19:26:07 smsl-n100 volumio[5846]: 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: 3 Aug 29 19:26:08 smsl-n100 volumio[5846]: info: TidalConnect service started! Aug 29 19:26:08 smsl-n100 volumio[5846]: info: Completed starting Core Plugins Aug 29 19:26:08 smsl-n100 volumio[5846]: info: ------------------------------------------- Aug 29 19:26:08 smsl-n100 volumio[5846]: info: ----- MyVolumio plugins startup ---- Aug 29 19:26:08 smsl-n100 volumio[5846]: info: ------------------------------------------- Aug 29 19:26:08 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:26:08 smsl-n100 volumio[5846]: info: Discovery: Getting this device information Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:26:08 smsl-n100 volumio[5846]: verbose: New Socket.io Connection to 192.168.1.32:3000 from 192.168.1.35 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:08 smsl-n100 volumio-remote-updater[5702]: No test mode Aug 29 19:26:08 smsl-n100 volumio-remote-updater[5702]: No alpha test mode Aug 29 19:26:08 smsl-n100 nmbd[714]: [2025/08/29 23:26:08.751571, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 29 19:26:08 smsl-n100 nmbd[714]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Aug 29 19:26:08 smsl-n100 volumio[5846]: verbose: New Socket.io Connection to 192.168.1.32:3000 from 192.168.1.35 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:08 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:09 smsl-n100 sudo[6257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 19:26:09 smsl-n100 sudo[6257]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:09 smsl-n100 sudo[6257]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:09 smsl-n100 sudo[6259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 19:26:09 smsl-n100 sudo[6259]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:09 smsl-n100 sudo[6259]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:09 smsl-n100 volumio[5846]: verbose: New Socket.io Connection to 192.168.1.32 from 192.168.1.35 UA: Mozilla/5.0 (Linux; Android 16; 2407FPN8EG Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Received Get System Info Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Discovery: Getting this device information Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Retrieving Cloud Streaming UI Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Getting Tidal Cloud Configuration Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Getting Qobuz Cloud Configuration Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Asking plugin for UI Config Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Getting Spotify Cloud Configuration Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Asking plugin for UI Config Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Saving Spotify Acccount Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Got it Aug 29 19:26:10 smsl-n100 volumio[5846]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Got Tidal Cloud Configuration Aug 29 19:26:10 smsl-n100 volumio[5846]: info: Got it Aug 29 19:26:10 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:10 smsl-n100 volumio[5846]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 29 19:26:11 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 29 19:26:11 smsl-n100 volumio[5846]: info: Loading i18n strings for locale es Aug 29 19:26:11 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 29 19:26:11 smsl-n100 volumio[5846]: info: Setting timezone to America/Caracas Aug 29 19:26:11 smsl-n100 sudo[6264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 29 19:26:11 smsl-n100 sudo[6264]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:11 smsl-n100 sudo[6264]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:11 smsl-n100 sudo[6267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Caracas /etc/localtime Aug 29 19:26:11 smsl-n100 sudo[6267]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:11 smsl-n100 sudo[6267]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:11 smsl-n100 sudo[6270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 29 19:26:11 smsl-n100 sudo[6270]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:11 smsl-n100 sudo[6270]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:11 smsl-n100 sudo[6273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Caracas Aug 29 19:26:11 smsl-n100 sudo[6273]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:11 smsl-n100 dbus-daemon[520]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.106' (uid=0 pid=6274 comm="/usr/bin/timedatectl set-timezone America/Caracas ") Aug 29 19:26:11 smsl-n100 systemd[1]: Starting Time & Date Service... Aug 29 19:26:11 smsl-n100 dbus-daemon[520]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 29 19:26:11 smsl-n100 systemd[1]: Started Time & Date Service. Aug 29 19:26:11 smsl-n100 sudo[6273]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:11 smsl-n100 volumio[5846]: info: Fetching Streaming Services browse cache Aug 29 19:26:11 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 19:26:11 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 29 19:26:12 smsl-n100 volumio[5846]: Updating browse sources language Aug 29 19:26:12 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:26:12 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 19:26:12 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 19:26:12 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 29 19:26:14 smsl-n100 volumio-remote-updater[5702]: No test mode Aug 29 19:26:14 smsl-n100 volumio-remote-updater[5702]: No alpha test mode Aug 29 19:26:14 smsl-n100 volumio[5846]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:26:14 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:26:15 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 29 19:26:15 smsl-n100 volumio[5846]: info: Loading i18n strings for locale es Aug 29 19:26:15 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Aug 29 19:26:15 smsl-n100 volumio[5846]: info: Setting timezone to America/Caracas Aug 29 19:26:15 smsl-n100 sudo[6296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Aug 29 19:26:15 smsl-n100 sudo[6296]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:16 smsl-n100 sudo[6296]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:16 smsl-n100 sudo[6299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/America/Caracas /etc/localtime Aug 29 19:26:16 smsl-n100 sudo[6299]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:16 smsl-n100 sudo[6299]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:16 smsl-n100 sudo[6302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Aug 29 19:26:16 smsl-n100 sudo[6302]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:16 smsl-n100 sudo[6302]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:16 smsl-n100 sudo[6305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone America/Caracas Aug 29 19:26:16 smsl-n100 sudo[6305]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:16 smsl-n100 sudo[6305]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:16 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 29 19:26:16 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 29 19:26:16 smsl-n100 sudo[6308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 19:26:16 smsl-n100 sudo[6308]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:16 smsl-n100 volumio[5846]: info: Fetching Streaming Services browse cache Aug 29 19:26:16 smsl-n100 volumio[5846]: Updating browse sources language Aug 29 19:26:16 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 19:26:16 smsl-n100 sudo[6308]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 29 19:26:17 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 29 19:26:19 smsl-n100 volumio-remote-updater[5702]: No test mode Aug 29 19:26:19 smsl-n100 volumio-remote-updater[5702]: No alpha test mode Aug 29 19:26:19 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 29 19:26:19 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 29 19:26:19 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:19 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:19 smsl-n100 volumio[5846]: info: Starting MyVolumio Remote Streaming Endpoints Aug 29 19:26:19 smsl-n100 volumio[5846]: info: MyVolumio not started Aug 29 19:26:19 smsl-n100 volumio[5846]: info: Initializing device activation check Aug 29 19:26:19 smsl-n100 volumio[5846]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 29 19:26:19 smsl-n100 volumio[5846]: 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' Aug 29 19:26:20 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:26:20 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:26:20 smsl-n100 volumio[5846]: info: Discovery: Getting this device information Aug 29 19:26:20 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:20 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:20 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:26:25 smsl-n100 volumio[5846]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:26:25 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:26:25 smsl-n100 volumio[5846]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:26:25 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:26:28 smsl-n100 volumio[5846]: error: MyVolumio Plugin failed to start in a timely fashion Aug 29 19:26:28 smsl-n100 volumio[5846]: [Metrics] CommandRouter: 57s 814.90ms Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumiosetStartupVolume Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::Close All Modals sent Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::Close All Modals sent Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Retrieving Cloud Streaming UI Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Retrieving Cloud Streaming UI Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Getting Tidal Cloud Configuration Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Getting Qobuz Cloud Configuration Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Asking plugin for UI Config Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Getting Spotify Cloud Configuration Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Asking plugin for UI Config Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Saving Spotify Acccount Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Got it Aug 29 19:26:28 smsl-n100 volumio[5846]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Getting Tidal Cloud Configuration Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Getting Qobuz Cloud Configuration Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Asking plugin for UI Config Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Getting Spotify Cloud Configuration Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Asking plugin for UI Config Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Saving Spotify Acccount Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Got it Aug 29 19:26:28 smsl-n100 volumio[5846]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Got Tidal Cloud Configuration Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Got Tidal Cloud Configuration Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Got it Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Got it Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Received Get System Version Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Received Get System Info Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:26:28 smsl-n100 volumio[5846]: info: Discovery: Getting this device information Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:28 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:26:29 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 29 19:26:29 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 19:26:29 smsl-n100 volumio[5846]: xcb_connection_has_error() returned true Aug 29 19:26:29 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 29 19:26:31 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:26:31 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:31 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:26:31 smsl-n100 volumio-remote-updater[5702]: No test mode Aug 29 19:26:31 smsl-n100 volumio-remote-updater[5702]: No alpha test mode Aug 29 19:26:31 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 19:26:34 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 19:26:34 smsl-n100 volumio[5846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 29 19:26:34 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:34 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:35 smsl-n100 volumio[5846]: info: BOOT COMPLETED Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 19:26:36 smsl-n100 volumio[5846]: info: Not Reporting Auto name since its the default one Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 29 19:26:36 smsl-n100 volumio[5846]: xcb_connection_has_error() returned true Aug 29 19:26:36 smsl-n100 sudo[6400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 29 19:26:36 smsl-n100 sudo[6400]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:36 smsl-n100 sudo[6400]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:36 smsl-n100 sudo[6402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 29 19:26:36 smsl-n100 sudo[6402]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:36 smsl-n100 systemd[1]: Stopping RAAT DAEMON... Aug 29 19:26:36 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Aug 29 19:26:36 smsl-n100 systemd[1]: raat-daemon.service: Succeeded. Aug 29 19:26:36 smsl-n100 systemd[1]: Stopped RAAT DAEMON. Aug 29 19:26:36 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 29 19:26:36 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 29 19:26:36 smsl-n100 systemd[1]: Started RAAT DAEMON. Aug 29 19:26:36 smsl-n100 sudo[6402]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:36 smsl-n100 volumio[5846]: info: Raat Daemon started successfully Aug 29 19:26:36 smsl-n100 volumio[5846]: info: Executing endpoint restartRAATSocket Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:36 smsl-n100 volumio[5846]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 29 19:26:36 smsl-n100 volumio[5846]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:26:36 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:26:39 smsl-n100 volumio[5846]: info: RAAT: Requesting Headphone Status Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreStateMachine::pushState Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioPushState Aug 29 19:26:39 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output update for this device Aug 29 19:26:39 smsl-n100 volumio[5846]: info: MRS: Pushing multiroomSync output Aug 29 19:26:39 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:39 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: MAXVOL, message: Aug 29 19:26:39 smsl-n100 volumio[5846]: error: Serial API: Failed to decode command: 100, message: undefined Aug 29 19:26:39 smsl-n100 volumio[5846]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 19:26:39 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 19:26:41 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 29 19:26:41 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:26:41 smsl-n100 volumio-remote-updater[5702]: No test mode Aug 29 19:26:41 smsl-n100 volumio-remote-updater[5702]: No alpha test mode Aug 29 19:26:41 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 19:26:46 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 29 19:26:46 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 19:26:46 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded. Aug 29 19:26:46 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 29 19:26:47 smsl-n100 volumio-remote-updater[5702]: No test mode Aug 29 19:26:47 smsl-n100 volumio-remote-updater[5702]: No alpha test mode Aug 29 19:26:47 smsl-n100 volumio[5846]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:26:47 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:26:50 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 19:26:50 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 19:26:50 smsl-n100 volumio[5846]: info: Discovery: Getting this device information Aug 29 19:26:50 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:26:50 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:26:50 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 19:26:52 smsl-n100 volumio[5846]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 29 19:26:52 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 29 19:26:55 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Aug 29 19:26:55 smsl-n100 volumio[5846]: info: System name has changed, restarting Shairport Sync Aug 29 19:26:55 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 19:26:55 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:55 smsl-n100 volumio[5846]: xcb_connection_has_error() returned true Aug 29 19:26:55 smsl-n100 volumio[5846]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 19:26:55 smsl-n100 volumio[5846]: xcb_connection_has_error() returned true Aug 29 19:26:55 smsl-n100 vtcs[6233]: [2025-08-29 19:26:55.861] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Aug 29 19:26:55 smsl-n100 vtcs[6233]: [2025-08-29 19:26:55.864] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Aug 29 19:26:55 smsl-n100 vtcs[6233]: [2025-08-29 19:26:55.865] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Aug 29 19:26:55 smsl-n100 sudo[6459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 19:26:55 smsl-n100 sudo[6459]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:55 smsl-n100 volumio[5846]: info: Discovery: Restarting Advertising due to device name change Aug 29 19:26:55 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Aug 29 19:26:55 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Aug 29 19:26:55 smsl-n100 systemd[1]: vtcs.service: Succeeded. Aug 29 19:26:56 smsl-n100 sudo[6462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 29 19:26:55 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Aug 29 19:26:56 smsl-n100 sudo[6462]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:56 smsl-n100 sudo[6459]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:56 smsl-n100 sudo[6462]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:56 smsl-n100 systemd[1]: upmpdcli.service: Succeeded. Aug 29 19:26:56 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Aug 29 19:26:56 smsl-n100 sudo[6467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 19:26:56 smsl-n100 sudo[6467]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:56 smsl-n100 sudo[6467]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:56 smsl-n100 volumio[5846]: xcb_connection_has_error() returned true Aug 29 19:26:56 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 19:26:56 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 19:26:56 smsl-n100 volumio[5846]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 19:26:56 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 29 19:26:56 smsl-n100 volumio[5846]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Al's Streamer' Aug 29 19:26:56 smsl-n100 volumio[5846]: /bin/sh: 1: Syntax error: Unterminated quoted string Aug 29 19:26:56 smsl-n100 volumio[5846]: info: Starting Shairport Sync Aug 29 19:26:56 smsl-n100 sudo[6473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Aug 29 19:26:56 smsl-n100 sudo[6473]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:56 smsl-n100 sudo[6473]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:56 smsl-n100 volumio[5846]: info: Permissions for /etc/hosts set Aug 29 19:26:56 smsl-n100 sudo[6476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 19:26:56 smsl-n100 sudo[6476]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:26:56 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 19:26:56 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Aug 29 19:26:56 smsl-n100 volumio[5846]: error: Cannot set new hostname: Error: Command failed: /usr/bin/sudo /bin/hostname al's-streamer Aug 29 19:26:56 smsl-n100 volumio[5846]: /bin/sh: 1: Syntax error: Unterminated quoted string Aug 29 19:26:56 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 19:26:56 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 29 19:26:56 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 19:26:56 smsl-n100 sudo[6476]: pam_unix(sudo:session): session closed for user root Aug 29 19:26:56 smsl-n100 volumio[5846]: info: Hostname now is al's-streamer Aug 29 19:26:56 smsl-n100 volumio[5846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 29 19:26:56 smsl-n100 volumio[5846]: info: Shairport-Sync Started Aug 29 19:26:56 smsl-n100 volumio[5846]: info: Discovery: A device disappeared from network Aug 29 19:26:59 smsl-n100 volumio[5846]: info: TidalConnect service stoped! Aug 29 19:26:59 smsl-n100 volumio[5846]: info: TidalConnect service stoped! Aug 29 19:26:59 smsl-n100 volumio[5846]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 29 19:26:59 smsl-n100 volumio[5846]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 29 19:27:00 smsl-n100 volumio[5846]: info: Discovery: Started advertising with name: Al's Streamer Aug 29 19:27:01 smsl-n100 sudo[6481]: volumio : unable to resolve host smsl-n100 Aug 29 19:27:01 smsl-n100 sudo[6481]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 29 19:27:01 smsl-n100 sudo[6481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Aug 29 19:27:01 smsl-n100 sudo[6481]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:27:01 smsl-n100 sudo[6481]: pam_unix(sudo:session): session closed for user root Aug 29 19:27:01 smsl-n100 volumio[5846]: info: Permissions for /etc/avahi/services/volumio.service Aug 29 19:27:02 smsl-n100 avahi-daemon[533]: Files changed, reloading. Aug 29 19:27:02 smsl-n100 avahi-daemon[533]: Loading service file /services/volumio.service. Aug 29 19:27:02 smsl-n100 volumio[5846]: info: Avahi name changed to al's-streamer Aug 29 19:27:02 smsl-n100 volumio[5846]: info: Discovery: adding c9c863b1-ae33-40a3-be33-1d000a6877c5 Aug 29 19:27:02 smsl-n100 volumio[5846]: info: Discovery: Found device Al's Streamer Aug 29 19:27:02 smsl-n100 volumio[5846]: info: CoreCommandRouter::volumioGetState Aug 29 19:27:02 smsl-n100 volumio[5846]: info: CorePlayQueue::getTrack 0 Aug 29 19:27:02 smsl-n100 volumio[5846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:27:02 smsl-n100 volumio[5846]: Error: The value NaN is not a number Aug 29 19:27:02 smsl-n100 volumio[5846]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Aug 29 19:27:02 smsl-n100 volumio[5846]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Aug 29 19:27:02 smsl-n100 volumio[5846]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Aug 29 19:27:02 smsl-n100 volumio[5846]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Aug 29 19:27:02 smsl-n100 volumio[5846]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Aug 29 19:27:02 smsl-n100 volumio[5846]: at Browser.emit (events.js:400:28) Aug 29 19:27:02 smsl-n100 volumio[5846]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Aug 29 19:27:02 smsl-n100 volumio[5846]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Aug 29 19:27:02 smsl-n100 volumio[5846]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Aug 29 19:27:02 smsl-n100 volumio[5846]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Aug 29 19:27:02 smsl-n100 volumio[5846]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Aug 29 19:27:02 smsl-n100 volumio[5846]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Aug 29 19:27:02 smsl-n100 volumio[5846]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Aug 29 19:27:02 smsl-n100 volumio[5846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 19:27:02 smsl-n100 avahi-daemon[533]: Service "Al's Streamer" (/services/volumio.service) successfully established. Aug 29 19:27:04 smsl-n100 sudo[6493]: volumio : unable to resolve host smsl-n100 Aug 29 19:27:04 smsl-n100 sudo[6493]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 29 19:27:04 smsl-n100 sudo[6493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 29 19:27:04 smsl-n100 sudo[6493]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 19:27:04 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Aug 29 19:27:04 smsl-n100 sudo[6493]: pam_unix(sudo:session): session closed for user root Aug 29 19:27:08 smsl-n100 sudo[6506]: volumio : unable to resolve host smsl-n100 Aug 29 19:27:08 smsl-n100 sudo[6506]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 29 19:27:08 smsl-n100 sudo[6506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-29 19:26 Aug 29 19:27:08 smsl-n100 sudo[6506]: 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"