-- Logs begin at Wed 2026-02-11 18:38:41 EST, end at Wed 2026-02-11 18:45:59 EST. -- Feb 11 18:44:00 volumio-lr volumio[10409]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 18:44:00 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 18:44:00 volumio-lr volumio[10409]: info: Successfully Updated MyVolumio device Feb 11 18:44:00 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:00 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:02 volumio-lr sudo[10842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 11 18:44:02 volumio-lr sudo[10842]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:44:02 volumio-lr autossh[10236]: received signal to exit (15) Feb 11 18:44:02 volumio-lr systemd[1]: Stopping MyVolumio SSH Tunnel... Feb 11 18:44:02 volumio-lr systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Feb 11 18:44:02 volumio-lr systemd[1]: sshtunnel.service: Succeeded. Feb 11 18:44:02 volumio-lr systemd[1]: Stopped MyVolumio SSH Tunnel. Feb 11 18:44:02 volumio-lr systemd[1]: Started MyVolumio SSH Tunnel. Feb 11 18:44:02 volumio-lr sudo[10842]: pam_unix(sudo:session): session closed for user root Feb 11 18:44:02 volumio-lr sudo[10846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 11 18:44:02 volumio-lr sudo[10846]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:44:02 volumio-lr autossh[10847]: port set to 0, monitoring disabled Feb 11 18:44:02 volumio-lr autossh[10847]: starting ssh (count 1) Feb 11 18:44:02 volumio-lr autossh[10847]: ssh child pid is 10851 Feb 11 18:44:02 volumio-lr sudo[10846]: pam_unix(sudo:session): session closed for user root Feb 11 18:44:02 volumio-lr volumio[10409]: info: Volumio BT Module successfully started Feb 11 18:44:02 volumio-lr volumio[10409]: info: Remote SSH Started Feb 11 18:44:02 volumio-lr volumiossh-tunnel[10845]: Warning: Permanently added '[us3.myvolumio.org]:2222,[159.203.57.91]:2222' (RSA) to the list of known hosts. Feb 11 18:44:02 volumio-lr volumio[10409]: info: TidalConnect service stoped! Feb 11 18:44:02 volumio-lr volumio[10409]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 11 18:44:02 volumio-lr volumio[10409]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 11 18:44:02 volumio-lr volumio[10409]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 11 18:44:02 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:02 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:02 volumio-lr sudo[10861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 11 18:44:02 volumio-lr sudo[10861]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:44:02 volumio-lr systemd[1]: Started Volumio Tidal Connect Service. Feb 11 18:44:02 volumio-lr sudo[10861]: pam_unix(sudo:session): session closed for user root Feb 11 18:44:02 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 18:44:02 volumio-lr volumio[10409]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 18:44:02 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:02 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:03 volumio-lr volumio[10409]: info: Executing endpoint tc_getconfig Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 11 18:44:03 volumio-lr vtcs[10863]: STARTING TidalConnect services, version: 1.6.1 Feb 11 18:44:03 volumio-lr vtcs[10863]: STARTED TidalConnect services. Feb 11 18:44:03 volumio-lr volumio[10409]: info: Executing endpoint tc_connect Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 11 18:44:03 volumio-lr volumio[10409]: info: Connecting to TidalConnect Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::servicePushState Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreStateMachine::pushState Feb 11 18:44:03 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushState Feb 11 18:44:03 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output update for this device Feb 11 18:44:03 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:03 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:03 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:03 volumio-lr volumio[10409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::servicePushState Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreStateMachine::pushState Feb 11 18:44:03 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushState Feb 11 18:44:03 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output update for this device Feb 11 18:44:03 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output Feb 11 18:44:03 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:03 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:03 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:03 volumio-lr volumio[10409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 11 18:44:03 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:03.143-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:44:03 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:03.144-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id="mnt/NAS/RAID/paul van dyk nothing but you hq.wmv" title="paul van dyk nothing but you hq.wmv" Feb 11 18:44:03 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:03.150-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:44:03 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:03.151-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id="mnt/NAS/RAID/paul van dyk nothing but you hq.wmv" title="paul van dyk nothing but you hq.wmv" Feb 11 18:44:04 volumio-lr volumio[10409]: info: MRS: Getting audio outputs on start Feb 11 18:44:04 volumio-lr volumio[10409]: info: MRS: Requesting all other devices output Feb 11 18:44:05 volumio-lr volumio[10409]: info: TidalConnect service started! Feb 11 18:44:05 volumio-lr volumio[10409]: [Metrics] CommandRouter: 43s 752.32ms Feb 11 18:44:05 volumio-lr volumio[10409]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 18:44:05 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:44:05 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:44:05 volumio-lr volumio[10409]: info: CoreCommandRouter::Close All Modals sent Feb 11 18:44:05 volumio-lr volumio[10409]: info: CoreCommandRouter::Close All Modals sent Feb 11 18:44:06 volumio-lr systemd[1]: Started Music Player Daemon. Feb 11 18:44:06 volumio-lr sudo[10787]: pam_unix(sudo:session): session closed for user root Feb 11 18:44:06 volumio-lr volumio[10409]: error: updateQueue error: null Feb 11 18:44:06 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 11 18:44:06 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 18:44:06 volumio-lr volumio[10409]: xcb_connection_has_error() returned true Feb 11 18:44:06 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 11 18:44:07 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:44:07 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:44:07 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 11 18:44:07 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 18:44:07 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 11 18:44:07 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 18:44:07 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:44:10 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:10 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:10 volumio-lr volumio[10409]: info: Listing playlists Feb 11 18:44:10 volumio-lr volumio[10409]: info: Listing playlists Feb 11 18:44:12 volumio-lr volumio[10409]: info: BOOT COMPLETED Feb 11 18:44:20 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:20 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:27 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioClearQueue Feb 11 18:44:27 volumio-lr volumio[10409]: info: CoreStateMachine::ClearQueue Feb 11 18:44:27 volumio-lr volumio[10409]: info: CoreStateMachine::stop Feb 11 18:44:27 volumio-lr volumio[10409]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 18:44:27 volumio-lr volumio[10409]: info: CorePlayQueue::clearPlayQueue Feb 11 18:44:27 volumio-lr volumio[10409]: info: CorePlayQueue::saveQueue Feb 11 18:44:27 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushState Feb 11 18:44:27 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output update for this device Feb 11 18:44:27 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output Feb 11 18:44:27 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:27 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:27 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushQueue Feb 11 18:44:27 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:27.710-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:44:27 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:27.710-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:44:30 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:30 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:30 volumio-lr volumio[10409]: info: Listing playlists Feb 11 18:44:30 volumio-lr volumio[10409]: info: Listing playlists Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioClearQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::ClearQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::stop Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::clearPlayQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::saveQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushState Feb 11 18:44:32 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output update for this device Feb 11 18:44:32 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushQueue Feb 11 18:44:32 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:32.605-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:44:32 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:32.605-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioClearQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::ClearQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::stop Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::clearPlayQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::saveQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushState Feb 11 18:44:32 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output update for this device Feb 11 18:44:32 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioClearQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::ClearQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::stop Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::clearPlayQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::saveQueue Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushState Feb 11 18:44:32 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output update for this device Feb 11 18:44:32 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:32 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:32 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushQueue Feb 11 18:44:32 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:32.624-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:44:32 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:32.624-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:44:32 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:32.625-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:44:32 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:32.626-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:44:37 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:37 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:40 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:40 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:41 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioClearQueue Feb 11 18:44:41 volumio-lr volumio[10409]: info: CoreStateMachine::ClearQueue Feb 11 18:44:41 volumio-lr volumio[10409]: info: CoreStateMachine::stop Feb 11 18:44:41 volumio-lr volumio[10409]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 18:44:41 volumio-lr volumio[10409]: info: CorePlayQueue::clearPlayQueue Feb 11 18:44:41 volumio-lr volumio[10409]: info: CorePlayQueue::saveQueue Feb 11 18:44:41 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushState Feb 11 18:44:41 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output update for this device Feb 11 18:44:41 volumio-lr volumio[10409]: info: MRS: Pushing multiroomSync output Feb 11 18:44:41 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:41 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:41 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioPushQueue Feb 11 18:44:41 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:41.519-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:44:41 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:44:41.520-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:44:43 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:43 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:50 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:44:50 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:44:50 volumio-lr volumio[10409]: info: Listing playlists Feb 11 18:44:50 volumio-lr volumio[10409]: info: Listing playlists Feb 11 18:45:00 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:00 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:10 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:10 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:10 volumio-lr volumio[10409]: info: Listing playlists Feb 11 18:45:10 volumio-lr volumio[10409]: info: Listing playlists Feb 11 18:45:14 volumio-lr volumio[10409]: Searching plugin music_service/mpd Feb 11 18:45:14 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 11 18:45:14 volumio-lr volumio[10409]: info: All search sources collected, pushing search results Feb 11 18:45:15 volumio-lr volumio[10409]: Searching plugin music_service/mpd Feb 11 18:45:15 volumio-lr volumio[10409]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 11 18:45:15 volumio-lr volumio[10409]: info: All search sources collected, pushing search results Feb 11 18:45:20 volumio-lr volumio[10409]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:20 volumio-lr volumio[10409]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:21 volumio-lr volumio[10409]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:45:21 volumio-lr volumio[10409]: TypeError: Cannot read property 'slice' of null Feb 11 18:45:21 volumio-lr volumio[10409]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 11 18:45:21 volumio-lr volumio[10409]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 11 18:45:21 volumio-lr volumio[10409]: at Object.onceWrapper (events.js:422:26) Feb 11 18:45:21 volumio-lr volumio[10409]: at ClientRequest.emit (events.js:315:20) Feb 11 18:45:21 volumio-lr volumio[10409]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Feb 11 18:45:21 volumio-lr volumio[10409]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Feb 11 18:45:21 volumio-lr volumio[10409]: at TLSSocket.socketOnData (_http_client.js:509:22) Feb 11 18:45:21 volumio-lr volumio[10409]: at TLSSocket.emit (events.js:315:20) Feb 11 18:45:21 volumio-lr volumio[10409]: at addChunk (internal/streams/readable.js:309:12) Feb 11 18:45:21 volumio-lr volumio[10409]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 11 18:45:21 volumio-lr volumio[10409]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Feb 11 18:45:21 volumio-lr volumio[10409]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 11 18:45:21 volumio-lr volumio[10409]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:45:22 volumio-lr sudo[11053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 18:44 Feb 11 18:45:22 volumio-lr sudo[11053]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:22 volumio-lr sudo[11053]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:22 volumio-lr vtcs[10863]: [2026-02-11 18:45:22.166] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 11 18:45:22 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:22.167-05:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 11 18:45:22 volumio-lr volumio-remote-updater[622]: [2026-02-11 18:45:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 11 18:45:22 volumio-lr volumio-remote-updater[622]: [2026-02-11 18:45:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 11 18:45:22 volumio-lr qobuz-connect[10817]: 20260211 18:45:22.167 [10817.10817] INFO SampleApp: Socket closed Feb 11 18:45:22 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:22.171-05:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:36932->127.0.0.1:3000: read: connection reset by peer" Feb 11 18:45:22 volumio-lr systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 11 18:45:22 volumio-lr systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 11 18:45:22 volumio-lr systemd[1]: Started dynamicswap service. Feb 11 18:45:22 volumio-lr systemd[1]: dynamicswap.service: Succeeded. Feb 11 18:45:22 volumio-lr systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 11 18:45:22 volumio-lr systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Feb 11 18:45:22 volumio-lr systemd[1]: Started dynamicswap service. Feb 11 18:45:22 volumio-lr systemd[1]: Stopped Volumio Backend Module. Feb 11 18:45:22 volumio-lr systemd[1]: Started Volumio Backend Module. Feb 11 18:45:22 volumio-lr systemd[1]: dynamicswap.service: Succeeded. Feb 11 18:45:23 volumio-lr volumio[11064]: info: ------------------------------------------- Feb 11 18:45:23 volumio-lr volumio[11064]: info: ----- Volumio3 ---- Feb 11 18:45:23 volumio-lr volumio[11064]: info: ------------------------------------------- Feb 11 18:45:23 volumio-lr volumio[11064]: info: ----- System startup ---- Feb 11 18:45:23 volumio-lr volumio[11064]: info: ------------------------------------------- Feb 11 18:45:23 volumio-lr volumio[11064]: info: MYVOLUMIO Environment detected Feb 11 18:45:23 volumio-lr volumio[11064]: info: Plugin folders cleanup Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning into folder /volumio/app/plugins/ Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning category audio_interface Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning category miscellanea Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning category music_service Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning category plugins.json Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning category system_controller Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning category user_interface Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning into folder /data/plugins/ Feb 11 18:45:23 volumio-lr volumio[11064]: info: Scanning category music_service Feb 11 18:45:23 volumio-lr volumio[11064]: info: Plugin folders cleanup completed Feb 11 18:45:23 volumio-lr volumio[11064]: info: ------------------------------------------- Feb 11 18:45:23 volumio-lr volumio[11064]: info: ----- Core plugins startup ---- Feb 11 18:45:23 volumio-lr volumio[11064]: info: ------------------------------------------- Feb 11 18:45:23 volumio-lr volumio[11064]: info: Loading plugins from folder /volumio/app/plugins/ Feb 11 18:45:23 volumio-lr volumio[11064]: info: Adding plugin upnp to MyMusic Plugins Feb 11 18:45:23 volumio-lr volumio[11064]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 11 18:45:23 volumio-lr volumio[11064]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 11 18:45:23 volumio-lr volumio[11064]: info: Loading plugins from folder /data/plugins/ Feb 11 18:45:23 volumio-lr volumio[11064]: info: Loading plugin "system"... Feb 11 18:45:23 volumio-lr volumio[11064]: info: Loading plugin "appearance"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "network"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Refreshing Cached IP Addresses Feb 11 18:45:24 volumio-lr sudo[11090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 18:45:24 volumio-lr sudo[11090]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:24 volumio-lr sudo[11090]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:24 volumio-lr sudo[11092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 18:45:24 volumio-lr sudo[11092]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:24 volumio-lr sudo[11092]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "services"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "volumio5onboarding"... Feb 11 18:45:24 volumio-lr sudo[11101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 18:45:24 volumio-lr sudo[11101]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "alsa_controller"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "wizard"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "networkfs"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Cannot mount NAS RAID at system boot, trial number 1 ,retrying in 5 seconds Feb 11 18:45:24 volumio-lr volumio[11064]: info: Starting Udev Watcher for removable devices Feb 11 18:45:24 volumio-lr volumio[11064]: info: Ignoring mount for partition: boot Feb 11 18:45:24 volumio-lr volumio[11064]: info: Ignoring mount for partition: volumio Feb 11 18:45:24 volumio-lr volumio[11064]: info: Ignoring mount for partition: volumio_data Feb 11 18:45:24 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "volumio_command_line_client"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "upnp"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: [1770853524615] Starting Upmpd Daemon Feb 11 18:45:24 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "my_music"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "mpd"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "upnp_browser"... Feb 11 18:45:24 volumio-lr volumio[11064]: info: Starting UPNP Browser Feb 11 18:45:24 volumio-lr volumio[11064]: info: Loading plugin "alarm-clock"... Feb 11 18:45:25 volumio-lr volumio[11064]: info: Loading plugin "airplay_emulation"... Feb 11 18:45:25 volumio-lr volumio[11064]: info: Starting Shairport Sync Feb 11 18:45:25 volumio-lr volumio[11064]: info: Loading plugin "last_100"... Feb 11 18:45:25 volumio-lr volumio[11064]: info: Loading plugin "webradio"... Feb 11 18:45:25 volumio-lr volumio[11064]: info: Loading plugin "i2s_dacs"... Feb 11 18:45:25 volumio-lr volumio[11064]: info: I2S DAC not set, start Auto-detection Feb 11 18:45:25 volumio-lr volumio[11064]: info: Loading plugin "volumiodiscovery"... Feb 11 18:45:25 volumio-lr volumio[11064]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 18:45:25 volumio-lr volumio[11064]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 18:45:25 volumio-lr node[11064]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 18:45:25 volumio-lr volumio[11064]: *** WARNING *** For more information see Feb 11 18:45:25 volumio-lr volumio[11064]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 18:45:25 volumio-lr volumio[11064]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 18:45:25 volumio-lr volumio[11064]: *** WARNING *** For more information see Feb 11 18:45:25 volumio-lr node[11064]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 18:45:25 volumio-lr node[11064]: *** WARNING *** For more information see Feb 11 18:45:25 volumio-lr node[11064]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 18:45:25 volumio-lr node[11064]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 18:45:25 volumio-lr node[11064]: *** WARNING *** For more information see Feb 11 18:45:25 volumio-lr volumio[11064]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 11 18:45:25 volumio-lr volumio[11064]: info: Discovery: Started advertising with name: Volumio-LR Feb 11 18:45:25 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 18:45:25 volumio-lr volumio[11064]: info: Loading plugin "mixcloud"... Feb 11 18:45:25 volumio-lr volumio[11064]: info: Loading plugin "soundcloud"... Feb 11 18:45:25 volumio-lr volumio[11064]: info: Loading plugin "youtube2"... Feb 11 18:45:27 volumio-lr volumio-remote-updater[622]: [2026-02-11 18:45:27] [connect] Successful connection Feb 11 18:45:27 volumio-lr volumio[11064]: info: Loading plugin "ytmusic"... Feb 11 18:45:28 volumio-lr sudo[11101]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:28 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:28.570-05:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 11 18:45:28 volumio-lr volumio[11064]: info: Loading plugin "outputs"... Feb 11 18:45:28 volumio-lr volumio[11064]: info: Loading plugin "albumart"... Feb 11 18:45:28 volumio-lr volumio[11064]: info: Plugin example_plugin is not enabled Feb 11 18:45:28 volumio-lr volumio[11064]: info: Loading plugin "inputs"... Feb 11 18:45:28 volumio-lr volumio[11064]: info: Loading plugin "updater_comm"... Feb 11 18:45:28 volumio-lr volumio[11064]: info: Plugin mpdemulation is not enabled Feb 11 18:45:28 volumio-lr volumio[11064]: info: Loading plugin "rest_api"... Feb 11 18:45:28 volumio-lr volumio[11064]: info: Loading plugin "websocket"... Feb 11 18:45:28 volumio-lr volumio[11064]: info: Starting Socket.io Server version 2.3.0 Feb 11 18:45:28 volumio-lr volumio[11064]: info: Loading plugin "podcast"... Feb 11 18:45:29 volumio-lr volumio[11064]: Forking 3 albumart workers Feb 11 18:45:29 volumio-lr volumio[11064]: info: ControllerPodcast::constructor Feb 11 18:45:29 volumio-lr volumio[11064]: info: Loading i18n strings for locale en Feb 11 18:45:29 volumio-lr volumio[11064]: Updating browse sources language Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::initPlayerControls Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: Express server listening on port 3000 Feb 11 18:45:29 volumio-lr volumio[11064]: [Metrics] WebUI: 6s 224.15ms Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreStateMachine::resetVolumioState Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreStateMachine::getcurrentVolume Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 18:45:29 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:29 volumio-lr volumio[11064]: info: Volumio Network Manager: Network status updated: 1 Feb 11 18:45:29 volumio-lr volumio[11064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 11 18:45:29 volumio-lr volumio[11064]: verbose: New Socket.io Connection to 192.168.50.253 from 192.168.50.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 11 18:45:29 volumio-lr volumio-remote-updater[622]: [2026-02-11 18:45:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1770853527 101 Feb 11 18:45:29 volumio-lr volumio[11064]: 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 Feb 11 18:45:29 volumio-lr volumio[11064]: info: Cannot mount NAS RAID at system boot, trial number 2 ,retrying in 5 seconds Feb 11 18:45:29 volumio-lr volumio[11064]: Starting albumart workers Feb 11 18:45:29 volumio-lr volumio[11064]: Starting albumart workers Feb 11 18:45:29 volumio-lr volumio[11064]: Starting albumart workers Feb 11 18:45:30 volumio-lr volumio[11064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 11 18:45:30 volumio-lr volumio[11064]: info: Received Get System Info Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:45:30 volumio-lr volumio[11064]: info: Discovery: Getting this device information Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:30 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:45:30 volumio-lr volumio[11064]: info: Reloading queue from file Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:45:30 volumio-lr volumio[11064]: info: VolumeController:: Volume=100 Mute =false Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreStateMachine::pushState Feb 11 18:45:30 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioPushState Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreStateMachine::updateTrackBlock Feb 11 18:45:30 volumio-lr volumio[11064]: info: CorePlayQueue::getTrackBlock Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 18:45:30 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:30.277-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:45:30 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:30.278-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreStateMachine::setRepeat null single undefined Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreStateMachine::pushState Feb 11 18:45:30 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioPushState Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreStateMachine::setRandom null Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreStateMachine::pushState Feb 11 18:45:30 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioPushState Feb 11 18:45:30 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:30.287-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:45:30 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:30.287-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:45:30 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:30.288-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:45:30 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:30.288-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:45:30 volumio-lr volumio[11064]: info: Setting Device type: Raspberry PI Feb 11 18:45:30 volumio-lr volumio[11064]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 11 18:45:30 volumio-lr volumio[11064]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Feb 11 18:45:30 volumio-lr volumio[11064]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 11 18:45:30 volumio-lr volumio[11064]: verbose: New Socket.io Connection to 192.168.50.253 from 192.168.50.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 11 18:45:30 volumio-lr volumio[11064]: verbose: New Socket.io Connection to 192.168.50.253 from 192.168.50.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 11 18:45:30 volumio-lr volumio[11064]: verbose: New Socket.io Connection to 192.168.50.253 from 192.168.50.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 18:45:30 volumio-lr volumio[11064]: info: VolumeController:: Volume=100 Mute =false Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreStateMachine::pushState Feb 11 18:45:30 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioPushState Feb 11 18:45:30 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:30.400-05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" state=STATUS_STOPPED positionMs=0 volume=100 Feb 11 18:45:30 volumio-lr volumio5-onboarding[1093]: time=2026-02-11T18:45:30.400-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.40:35798 @ 0x311a960" id= title= Feb 11 18:45:30 volumio-lr volumio[11064]: verbose: New Socket.io Connection to 192.168.50.253 from 192.168.50.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 11 18:45:30 volumio-lr volumio[11064]: info: Completed loading Core Plugins Feb 11 18:45:30 volumio-lr volumio[11064]: info: Preparing to generate the ALSA configuration file Feb 11 18:45:30 volumio-lr volumio[11064]: info: Asound.conf file written Feb 11 18:45:30 volumio-lr sudo[11186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 11 18:45:30 volumio-lr sudo[11186]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:30 volumio-lr sudo[11186]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:30 volumio-lr volumio[11064]: info: Output device has changed, restarting MPD Feb 11 18:45:30 volumio-lr sudo[11191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 18:45:30 volumio-lr sudo[11191]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:30 volumio-lr sudo[11191]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:30 volumio-lr volumio[11064]: info: Output device has changed, restarting Shairport Sync Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:30 volumio-lr sudo[11194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 18:45:30 volumio-lr sudo[11194]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:30 volumio-lr systemd[1]: Stopping Music Player Daemon... Feb 11 18:45:30 volumio-lr volumio[11064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:45:30 volumio-lr volumio[11064]: info: ___________ START PLUGINS ___________ Feb 11 18:45:30 volumio-lr volumio[11064]: info: ControllerMpd::onStart: Initializing MPD Feb 11 18:45:30 volumio-lr volumio[11064]: info: Creating MPD Configuration file Feb 11 18:45:30 volumio-lr systemd[1]: mpd.service: Succeeded. Feb 11 18:45:30 volumio-lr systemd[1]: Stopped Music Player Daemon. Feb 11 18:45:30 volumio-lr sudo[11200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 11 18:45:30 volumio-lr sudo[11200]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:30 volumio-lr sudo[11204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:45:30 volumio-lr volumio[11064]: info: [1770853530671] CoreMusicLibrary::Adding element Media Servers Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:30 volumio-lr volumio[11064]: info: UPNP Browser: Client initialized successfully Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:30 volumio-lr systemd[1]: Starting Music Player Daemon... Feb 11 18:45:30 volumio-lr sudo[11204]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:30 volumio-lr sudo[11204]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:30 volumio-lr sudo[11207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 18:45:30 volumio-lr sudo[11207]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:30 volumio-lr systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 11 18:45:30 volumio-lr systemd[1]: mpd.service: Succeeded. Feb 11 18:45:30 volumio-lr systemd[1]: Stopped Music Player Daemon. Feb 11 18:45:30 volumio-lr sudo[11200]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:30 volumio-lr systemd[1]: Starting Music Player Daemon... Feb 11 18:45:30 volumio-lr volumio[11064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:30 volumio-lr volumio[11064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:45:30 volumio-lr volumio[11064]: info: [1770853530784] CoreMusicLibrary::Adding element Last_100 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:45:30 volumio-lr volumio[11064]: info: [1770853530787] CoreMusicLibrary::Adding element Webradio Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:45:30 volumio-lr sudo[11212]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 18:45:30 volumio-lr volumio[11064]: info: Initializing BBC Radios Feb 11 18:45:30 volumio-lr sudo[11212]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:30 volumio-lr sudo[11212]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:45:30 volumio-lr volumio[11064]: info: [1770853530870] CoreMusicLibrary::Adding element Mixcloud Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source Mixcloud Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:45:30 volumio-lr volumio[11064]: info: [1770853530877] CoreMusicLibrary::Adding element SoundCloud Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source Mixcloud Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source SoundCloud Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:45:30 volumio-lr volumio[11064]: info: [1770853530883] CoreMusicLibrary::Adding element YouTube2 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source Mixcloud Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source SoundCloud Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source YouTube2 Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:45:30 volumio-lr volumio[11064]: info: [1770853530888] CoreMusicLibrary::Adding element YouTube Music Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source Mixcloud Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source SoundCloud Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source YouTube2 Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source YouTube Music Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 18:45:30 volumio-lr volumio[11064]: info: [1770853530895] CoreMusicLibrary::Adding element Podcast Feb 11 18:45:30 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source Mixcloud Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source SoundCloud Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source YouTube2 Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source YouTube Music Feb 11 18:45:30 volumio-lr volumio[11064]: Cannot find translation for source Podcast Feb 11 18:45:30 volumio-lr volumio[11064]: info: Volumio Calling Home Feb 11 18:45:31 volumio-lr volumio[11064]: info: Discovery: adding fc45e278-130a-42c1-9cb6-484a531e67f7 Feb 11 18:45:31 volumio-lr volumio[11064]: info: Discovery: Found device Volumio-LR Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: info: Discovery: this is already registered, fc45e278-130a-42c1-9cb6-484a531e67f7 Feb 11 18:45:31 volumio-lr volumio[11064]: info: Discovery: Found device Volumio-LR Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: verbose: New Socket.io Connection to 192.168.50.253 from 192.168.50.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 11 18:45:31 volumio-lr volumio[11064]: info: MPD Permissions set Feb 11 18:45:31 volumio-lr volumio[11064]: info: MPD Permissions set Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 18:45:31 volumio-lr volumio[11064]: info: Listing playlists Feb 11 18:45:31 volumio-lr volumio[11064]: info: Listing playlists Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: info: Starting Shairport Sync Feb 11 18:45:31 volumio-lr volumio[11064]: info: Starting Shairport Sync Feb 11 18:45:31 volumio-lr sudo[11240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:45:31 volumio-lr sudo[11240]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:31 volumio-lr volumio[11064]: info: Starting Shairport Sync Feb 11 18:45:31 volumio-lr sudo[11243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:45:31 volumio-lr sudo[11243]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:31 volumio-lr systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 11 18:45:31 volumio-lr systemd[1]: shairport-sync.service: Succeeded. Feb 11 18:45:31 volumio-lr systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr sudo[11246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 18:45:31 volumio-lr sudo[11246]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:31 volumio-lr systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 11 18:45:31 volumio-lr volumio[11064]: info: Volumio called home Feb 11 18:45:31 volumio-lr volumio[11064]: info: Received Get System Info Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:45:31 volumio-lr volumio[11064]: info: Discovery: Getting this device information Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 18:45:31 volumio-lr volumio[11064]: info: Received Get System Info Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:45:31 volumio-lr volumio[11064]: info: Discovery: Getting this device information Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:31 volumio-lr volumio[11064]: info: Listing playlists Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetQueue Feb 11 18:45:31 volumio-lr volumio[11064]: info: CoreStateMachine::getQueue Feb 11 18:45:31 volumio-lr volumio[11064]: info: CorePlayQueue::getQueue Feb 11 18:45:31 volumio-lr sudo[11240]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:31 volumio-lr systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 11 18:45:31 volumio-lr systemd[1]: shairport-sync.service: Succeeded. Feb 11 18:45:31 volumio-lr systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 11 18:45:31 volumio-lr systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 11 18:45:31 volumio-lr sudo[11246]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:31 volumio-lr sudo[11243]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:31 volumio-lr volumio[11064]: info: Shairport-Sync Started Feb 11 18:45:31 volumio-lr volumio[11064]: Error adding Membership: Error: addMembership EINVAL Feb 11 18:45:31 volumio-lr volumio[11064]: info: Shairport-Sync Started Feb 11 18:45:31 volumio-lr volumio[11064]: info: Shairport-Sync Started Feb 11 18:45:31 volumio-lr mpd[11218]: Feb 11 18:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 18:45:33 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 18:45:34 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 18:45:34 volumio-lr sudo[11307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 18:45:34 volumio-lr sudo[11307]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:34 volumio-lr sudo[11307]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:34 volumio-lr sudo[11310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 18:45:34 volumio-lr sudo[11310]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:34 volumio-lr sudo[11310]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:34 volumio-lr volumio[11064]: info: Cannot mount NAS RAID at system boot, trial number 3 ,retrying in 5 seconds Feb 11 18:45:34 volumio-lr sudo[11313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 11 18:45:34 volumio-lr sudo[11313]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:34 volumio-lr sudo[11313]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:34 volumio-lr volumio[11064]: info: Upmpdcli Daemon Started Feb 11 18:45:34 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 18:45:35 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 18:45:38 volumio-lr systemd[1]: Started Music Player Daemon. Feb 11 18:45:39 volumio-lr sudo[11207]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:39 volumio-lr sudo[11194]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:39 volumio-lr volumio[11064]: info: Completed starting Core Plugins Feb 11 18:45:39 volumio-lr volumio[11064]: info: ------------------------------------------- Feb 11 18:45:39 volumio-lr volumio[11064]: info: ----- MyVolumio plugins startup ---- Feb 11 18:45:39 volumio-lr volumio[11064]: info: ------------------------------------------- Feb 11 18:45:39 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 18:45:39 volumio-lr volumio[11064]: error: MPD error: The expression evaluated to a falsy value: Feb 11 18:45:39 volumio-lr volumio[11064]: assert.ok(self.idling) Feb 11 18:45:39 volumio-lr volumio[11064]: error: The expression evaluated to a falsy value: Feb 11 18:45:39 volumio-lr volumio[11064]: assert.ok(self.idling) Feb 11 18:45:39 volumio-lr volumio[11064]: info: MPD running with PID11218 Feb 11 18:45:39 volumio-lr volumio[11064]: ,establishing connection Feb 11 18:45:39 volumio-lr volumio[11064]: error: updateQueue error: null Feb 11 18:45:39 volumio-lr volumio[11064]: error: updateQueue error: null Feb 11 18:45:39 volumio-lr volumio[11064]: info: Cannot mount NAS RAID at system boot, trial number 4 ,retrying in 5 seconds Feb 11 18:45:40 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:40 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:44 volumio-lr volumio[11064]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 11 18:45:44 volumio-lr volumio[11064]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 11 18:45:45 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 18:45:45 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 11 18:45:45 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 18:45:45 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 11 18:45:48 volumio-lr volumio[11064]: info: Adding plugin bluetooth to MyMusic Plugins Feb 11 18:45:48 volumio-lr volumio[11064]: info: Adding plugin multiroom to MyMusic Plugins Feb 11 18:45:48 volumio-lr volumio[11064]: info: Adding plugin metavolumio to MyMusic Plugins Feb 11 18:45:48 volumio-lr volumio[11064]: info: Adding plugin cd_controller to MyMusic Plugins Feb 11 18:45:48 volumio-lr volumio[11064]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 11 18:45:48 volumio-lr volumio[11064]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 11 18:45:48 volumio-lr volumio[11064]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 11 18:45:48 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 11 18:45:49 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 11 18:45:49 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 11 18:45:49 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:49 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:49 volumio-lr volumio[11064]: info: Starting MyVolumio Remote Streaming Endpoints Feb 11 18:45:49 volumio-lr volumio[11064]: info: MyVolumio login type: Token Feb 11 18:45:49 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 11 18:45:49 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 11 18:45:49 volumio-lr volumio[11064]: info: Starting Streaming Service Transparent Proxy Feb 11 18:45:49 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 11 18:45:49 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 11 18:45:49 volumio-lr volumio[11064]: info: Streaming services startup Feb 11 18:45:49 volumio-lr volumio[11064]: info: Starting Streaming Daemon Feb 11 18:45:49 volumio-lr sudo[11345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 18:45:49 volumio-lr sudo[11345]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:49 volumio-lr sudo[11345]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:49 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 11 18:45:50 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 18:45:50 volumio-lr volumio[11064]: error: Cannot start Volumio Streaming Daemon Feb 11 18:45:50 volumio-lr volumio[11064]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 18:45:50 volumio-lr volumio[11064]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 18:45:50 volumio-lr volumio[11064]: STREAMING PROXY: Starting server on port 3245 Feb 11 18:45:50 volumio-lr volumio[11064]: Node JS runtime: 14 Feb 11 18:45:51 volumio-lr volumio[11064]: info: CoreCommandRouter::volumioGetState Feb 11 18:45:51 volumio-lr volumio[11064]: info: CorePlayQueue::getTrack 0 Feb 11 18:45:51 volumio-lr volumio[11064]: info: Listing playlists Feb 11 18:45:51 volumio-lr volumio[11064]: info: Listing playlists Feb 11 18:45:52 volumio-lr volumio[11064]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 11 18:45:53 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 18:45:55 volumio-lr volumio[11064]: Cannot compose Albumart path Feb 11 18:45:55 volumio-lr volumio[11064]: Cannot compose Albumart path Feb 11 18:45:56 volumio-lr volumio[11064]: info: MyVolumio token set successfully Feb 11 18:45:56 volumio-lr volumio[11064]: info: MYVOLUMIO: Adding device Feb 11 18:45:56 volumio-lr volumio[11064]: info: MYVOLUMIO: Evaluating Server Feb 11 18:45:57 volumio-lr volumio[11064]: info: MyVolumio Plan changed: premium Feb 11 18:45:57 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 11 18:45:57 volumio-lr volumio[11064]: info: Removing browser output: myVolumio user plan is not superstar Feb 11 18:45:57 volumio-lr volumio[11064]: info: Removing audio output: Feb 11 18:45:57 volumio-lr volumio[11064]: info: MYVOLUMIO: Adding device Feb 11 18:45:57 volumio-lr volumio[11064]: info: MYVOLUMIO: Evaluating Server Feb 11 18:45:57 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 11 18:45:58 volumio-lr volumio[11064]: info: Setting Geolocation for MyVolumio to us3 Feb 11 18:45:58 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:58 volumio-lr volumio[11064]: info: Remote config written successfully Feb 11 18:45:58 volumio-lr volumio[11064]: info: Starting Tunnel 1 Feb 11 18:45:58 volumio-lr volumio[11064]: info: Starting Tunnel Connection Checker Feb 11 18:45:58 volumio-lr volumio[11064]: info: Completed starting MyVolumio Plugin Feb 11 18:45:58 volumio-lr volumio[11064]: info: Setting Geolocation for MyVolumio to us3 Feb 11 18:45:58 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:58 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:58 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 18:45:58 volumio-lr volumio[11064]: info: MYVolumio Device enabled Feb 11 18:45:58 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 11 18:45:58 volumio-lr volumio[11064]: info: MyVolumio status changed Feb 11 18:45:58 volumio-lr volumio[11064]: info: Streaming services startup Feb 11 18:45:58 volumio-lr volumio[11064]: info: Starting Streaming Daemon Feb 11 18:45:58 volumio-lr sudo[11405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 18:45:58 volumio-lr sudo[11405]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:58 volumio-lr volumio[11064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 11 18:45:58 volumio-lr sudo[11405]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:58 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 11 18:45:58 volumio-lr volumio[11064]: info: [1770853558384] Starting BluetoothController Feb 11 18:45:58 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 11 18:45:59 volumio-lr sudo[11408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 11 18:45:59 volumio-lr sudo[11408]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:59 volumio-lr sudo[11408]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:59 volumio-lr volumio[11064]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 11 18:45:59 volumio-lr volumio[11064]: info: MRS: MultiRoom plugin initialized Feb 11 18:45:59 volumio-lr volumio[11064]: info: MRS: STOPPING SNAPCLIENT Feb 11 18:45:59 volumio-lr volumio[11064]: info: MRS: Snap server stop Feb 11 18:45:59 volumio-lr sudo[11424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 11 18:45:59 volumio-lr sudo[11424]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:59 volumio-lr sudo[11424]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:59 volumio-lr volumio[11064]: info: MRS: STOPPING volumioStreaming Feb 11 18:45:59 volumio-lr sudo[11427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 11 18:45:59 volumio-lr sudo[11427]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:59 volumio-lr sudo[11427]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:59 volumio-lr sudo[11430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 11 18:45:59 volumio-lr sudo[11430]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:59 volumio-lr sudo[11430]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 11 18:45:59 volumio-lr sudo[11433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 11 18:45:59 volumio-lr sudo[11433]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 18:45:59 volumio-lr sudo[11433]: pam_unix(sudo:session): session closed for user root Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 11 18:45:59 volumio-lr volumio[11064]: info: Preparing to generate the ALSA configuration file Feb 11 18:45:59 volumio-lr volumio[11064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:45:59 volumio-lr volumio[11064]: [Error: 3065599040:error:0607A082:digital envelope routines:EVP_CIPHER_CTX_set_key_length:invalid key length:../deps/openssl/openssl/crypto/evp/evp_enc.c:611: Feb 11 18:45:59 volumio-lr volumio[11064]: 3065599040:error:0607A082:digital envelope routines:EVP_CIPHER_CTX_set_key_length:invalid key length:../deps/openssl/openssl/crypto/evp/evp_enc.c:611: Feb 11 18:45:59 volumio-lr volumio[11064]: ] { Feb 11 18:45:59 volumio-lr volumio[11064]: library: 'digital envelope routines', Feb 11 18:45:59 volumio-lr volumio[11064]: function: 'EVP_CIPHER_CTX_set_key_length', Feb 11 18:45:59 volumio-lr volumio[11064]: reason: 'invalid key length', Feb 11 18:45:59 volumio-lr volumio[11064]: code: 'ERR_SSL_INVALID_KEY_LENGTH' Feb 11 18:45:59 volumio-lr volumio[11064]: } Feb 11 18:45:59 volumio-lr volumio[11064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:45:59 volumio-lr sudo[11444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 18:44 Feb 11 18:45:59 volumio-lr sudo[11444]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"