Oct 12 22:34:02 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 22:34:03 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:03 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:34:07 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 22:34:07 sibermusic volumio[1393]: info: Executing endpoint metavolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: Executing endpoint metavolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: Executing endpoint metavolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: Executing endpoint metavolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: Executing endpoint metavolumio Oct 12 22:34:07 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 12 22:34:15 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:15 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:34:24 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:24 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:34:31 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:31 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:34:31 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:31 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:34:31 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:31.788+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s Oct 12 22:34:31 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:34:31 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:34:31 sibermusic volumio[1393]: info: Discovery: Getting this device information Oct 12 22:34:31 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:31 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:34:31 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:34:31 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:31.981+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=http://pushupdates.volumio.org duration=192.509049ms Oct 12 22:34:31 sibermusic volumio[1393]: verbose: New Socket.io Connection to 192.168.68.101:3000 from 192.168.68.107 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Oct 12 22:34:32 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 22:34:32 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 22:34:32 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 22:34:32 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.035+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=247.633269ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.093+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=304.636326ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.101+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=312.030489ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.136+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://google.com duration=346.940206ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.151+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://securetoken.googleapis.com duration=362.595608ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.175+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://www.googleapis.com duration=386.250154ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.194+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://functions.volumio.cloud duration=405.623453ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.195+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://functions.volumio.cloud duration=406.380435ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.254+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=465.796368ms Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.321+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=http://cddb.volumio.org duration=532.901593ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 12 22:34:32 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:32.491+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=http://plugins.volumio.org duration=701.810966ms Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:34:33 sibermusic volumio[1393]: info: Received Get System Version Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 22:34:33 sibermusic volumio[1393]: info: Received Get System Info Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:34:33 sibermusic volumio[1393]: info: Discovery: Getting this device information Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:33 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 22:34:33 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 12 22:34:36 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:34:36 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:34:36 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 22:34:37 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:34:37.287+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:46686 @ 0x352a210" latency=-1.130798011s timeout=10s endpoint=https://database.volumio.cloud duration=5.497874455s Oct 12 22:34:41 sibermusic volumio[1393]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 22:34:48 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:48 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:34:56 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:34:56 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:04 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:04 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:12 sibermusic volumio[1393]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:12 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:17 sibermusic volumio[1393]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/glass-0.9.41.zip Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.297+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:17 sibermusic volumio[1393]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/glass-0.9.41.zip Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::stop Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::stPlaybackTimer Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::updateTrackBlock Oct 12 22:35:17 sibermusic volumio[1393]: info: CorePlayQueue::getTrackBlock Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::pushState Oct 12 22:35:17 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::volumioPushState Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 22:35:17 sibermusic volumio[1393]: info: MRS: Pushing multiroomSync output update for this device Oct 12 22:35:17 sibermusic volumio[1393]: info: MRS: Pushing multiroomSync output Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::serviceStop Oct 12 22:35:17 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::serviceStop Oct 12 22:35:17 sibermusic volumio[1393]: info: ControllerMpd::stop Oct 12 22:35:17 sibermusic volumio[1393]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.311+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.107:46686 @ 0x352a210" state=STATUS_STOPPED positionMs=0 volume=86 Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.311+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.107:46686 @ 0x352a210" id="music-library/INTERNAL/sibernas/sibernas/Hazel O'Connor/Breaking Glass/01 Writing On The Wall.flac" title="Writing On The Wall" Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.314+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.315+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:17 sibermusic volumio[1393]: info: FusionDsp - Volumio is not playing Oct 12 22:35:17 sibermusic volumio[1393]: info: FusionDsp - Clipped samples monitor stopped Oct 12 22:35:17 sibermusic volumio[1393]: info: Oct 12 22:35:17 sibermusic volumio[1393]: ---------------------------- MPD announces state update: player Oct 12 22:35:17 sibermusic volumio[1393]: info: sendMpdCommand stop took 27 milliseconds Oct 12 22:35:17 sibermusic volumio[1393]: info: ControllerMpd::getState Oct 12 22:35:17 sibermusic volumio[1393]: verbose: ControllerMpd::sendMpdCommand status Oct 12 22:35:17 sibermusic volumio[1393]: info: Removing glass_artistfanart REST Endpoint Oct 12 22:35:17 sibermusic volumio[1393]: info: sendMpdCommand status took 34 milliseconds Oct 12 22:35:17 sibermusic volumio[1393]: verbose: ControllerMpd::parseState Oct 12 22:35:17 sibermusic volumio[1393]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 12 22:35:17 sibermusic systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 12 22:35:17 sibermusic volumio[1393]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 12 22:35:17 sibermusic volumio[1393]: verbose: ControllerMpd::parseTrackInfo Oct 12 22:35:17 sibermusic volumio[1393]: info: ControllerMpd::pushState Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::servicePushState Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::pushState Oct 12 22:35:17 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::volumioPushState Oct 12 22:35:17 sibermusic volumio[1393]: info: MRS: Pushing multiroomSync output update for this device Oct 12 22:35:17 sibermusic volumio[1393]: info: MRS: Pushing multiroomSync output Oct 12 22:35:17 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:17 sibermusic volumio[1393]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Writing On The Wall","artist":"Hazel O'Connor","album":"Breaking Glass","uri":"INTERNAL/sibernas/sibernas/Hazel O'Connor/Breaking Glass/01 Writing On The Wall.flac","trackType":"flac"} Oct 12 22:35:17 sibermusic volumio[1393]: verbose: CURRENT POSITION 0 Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::syncState stateService stop Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::syncState currentStatus stop Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::pushState Oct 12 22:35:17 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::volumioPushState Oct 12 22:35:17 sibermusic volumio[1393]: info: MRS: Pushing multiroomSync output update for this device Oct 12 22:35:17 sibermusic volumio[1393]: info: MRS: Pushing multiroomSync output Oct 12 22:35:17 sibermusic volumio[1393]: info: No code Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreStateMachine::pushState Oct 12 22:35:17 sibermusic volumio[1393]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:17 sibermusic volumio[1393]: info: CoreCommandRouter::volumioPushState Oct 12 22:35:17 sibermusic volumio[1393]: info: MRS: Pushing multiroomSync output update for this device Oct 12 22:35:17 sibermusic volumio[1393]: info: MRS: Pushing multiroomSync output Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.382+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.107:46686 @ 0x352a210" state=STATUS_STOPPED positionMs=256 volume=86 Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.382+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.107:46686 @ 0x352a210" id="music-library/INTERNAL/sibernas/sibernas/Hazel O'Connor/Breaking Glass/01 Writing On The Wall.flac" title="Writing On The Wall" Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.383+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.107:46686 @ 0x352a210" state=STATUS_STOPPED positionMs=256 volume=86 Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.383+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.107:46686 @ 0x352a210" id="music-library/INTERNAL/sibernas/sibernas/Hazel O'Connor/Breaking Glass/01 Writing On The Wall.flac" title="Writing On The Wall" Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.383+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.107:46686 @ 0x352a210" state=STATUS_STOPPED positionMs=256 volume=86 Oct 12 22:35:17 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:17.383+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.107:46686 @ 0x352a210" id="music-library/INTERNAL/sibernas/sibernas/Hazel O'Connor/Breaking Glass/01 Writing On The Wall.flac" title="Writing On The Wall" Oct 12 22:35:17 sibermusic volumio[1393]: info: ------------------------------ 50ms Oct 12 22:35:17 sibermusic volumio[1393]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 12 22:35:17 sibermusic volumio[1393]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 12 22:35:17 sibermusic systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 12 22:35:17 sibermusic systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 12 22:35:18 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:18.458+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:18 sibermusic volumio[1393]: info: Check plugin dependencies Oct 12 22:35:18 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:18.472+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:18 sibermusic volumio[1393]: info: Rename folder Oct 12 22:35:18 sibermusic volumio[1393]: info: Folder /tmp/downloaded_plugin.zip removed Oct 12 22:35:18 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:18.487+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:18 sibermusic volumio[1393]: info: Move to category Oct 12 22:35:19 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:19.177+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:19 sibermusic volumio[1393]: info: Checking if install.sh is present Oct 12 22:35:19 sibermusic volumio[1393]: info: Executing install.sh Oct 12 22:35:19 sibermusic sudo[32313]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/glass/install.sh Oct 12 22:35:19 sibermusic sudo[32313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:19 sibermusic volumio[1393]: info: Installing Glass Oct 12 22:35:19 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:19.216+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:19 sibermusic volumio[1393]: info: Architecture: arm, variant: volumio, hardware: pi Oct 12 22:35:19 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:19.222+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:19 sibermusic volumio[1393]: info: Tap: /data/plugins/user_interface/glass/lib/arm/libglasstap.so Oct 12 22:35:19 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:19.266+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:19 sibermusic volumio[1393]: info: The running X session admits the volumio user Oct 12 22:35:19 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:19.324+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:19 sibermusic volumio[1393]: info: Glass installed Oct 12 22:35:19 sibermusic sudo[32313]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:19 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:19.328+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:19 sibermusic volumio[1393]: Plugin install end detected on script Oct 12 22:35:19 sibermusic volumio[1393]: info: Install script completed Oct 12 22:35:19 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:19.332+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:19 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:19.333+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 12 22:35:19 sibermusic volumio[1393]: info: Done installing plugin. Oct 12 22:35:19 sibermusic volumio[1393]: info: Enabling plugin glass Oct 12 22:35:19 sibermusic volumio[1393]: info: Folder /tmp/downloaded_plugin.zip removed Oct 12 22:35:19 sibermusic volumio[1393]: info: Folder /data/temp removed Oct 12 22:35:19 sibermusic volumio[1393]: info: Folder /tmp/plugins removed Oct 12 22:35:22 sibermusic sudo[32372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl --no-block restart volumio Oct 12 22:35:22 sibermusic sudo[32372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:22 sibermusic sudo[32372]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:22 sibermusic systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 12 22:35:22 sibermusic systemd[1]: Stopping volumio.service - Volumio Backend Module... Oct 12 22:35:22 sibermusic systemd[1]: dynamicswap.service: Deactivated successfully. Oct 12 22:35:22 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:22.425+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 12 22:35:22 sibermusic vtcs[2501]: [2026-10-12 22:35:22.425] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 12 22:35:22 sibermusic volumio-remote-updater[1012]: [2026-10-12 22:35:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 12 22:35:22 sibermusic volumio-remote-updater[1012]: [2026-10-12 22:35:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 12 22:35:22 sibermusic volumio5-onboarding[1732]: time=2026-10-12T22:35:22.427+02:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:37398->127.0.0.1:3000: read: connection reset by peer" Oct 12 22:35:22 sibermusic systemd[1]: volumio.service: Deactivated successfully. Oct 12 22:35:22 sibermusic systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 12 22:35:22 sibermusic systemd[1]: volumio.service: Consumed 54min 3.248s CPU time. Oct 12 22:35:22 sibermusic systemd[1]: Started volumio.service - Volumio Backend Module. Oct 12 22:35:22 sibermusic volumio[32389]: info: ------------------------------------------- Oct 12 22:35:22 sibermusic volumio[32389]: info: ----- Volumio3 ---- Oct 12 22:35:22 sibermusic volumio[32389]: info: ------------------------------------------- Oct 12 22:35:22 sibermusic volumio[32389]: info: ----- System startup ---- Oct 12 22:35:22 sibermusic volumio[32389]: info: ------------------------------------------- Oct 12 22:35:22 sibermusic volumio[32389]: info: MYVOLUMIO Environment detected Oct 12 22:35:22 sibermusic volumio[32389]: info: Plugin folders cleanup Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning into folder /volumio/app/plugins/ Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category audio_interface Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category miscellanea Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category music_service Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category plugins.json Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category system_controller Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category user_interface Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning into folder /data/plugins/ Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category audio_interface Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category music_service Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category system_controller Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category system_hardware Oct 12 22:35:22 sibermusic volumio[32389]: info: Scanning category user_interface Oct 12 22:35:22 sibermusic volumio[32389]: info: Plugin folders cleanup completed Oct 12 22:35:22 sibermusic volumio[32389]: info: ------------------------------------------- Oct 12 22:35:22 sibermusic volumio[32389]: info: ----- Core plugins startup ---- Oct 12 22:35:22 sibermusic volumio[32389]: info: ------------------------------------------- Oct 12 22:35:22 sibermusic volumio[32389]: info: Loading plugins from folder /volumio/app/plugins/ Oct 12 22:35:22 sibermusic volumio[32389]: info: Adding plugin upnp to MyMusic Plugins Oct 12 22:35:22 sibermusic volumio[32389]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 12 22:35:22 sibermusic volumio[32389]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 12 22:35:22 sibermusic volumio[32389]: info: Loading plugins from folder /data/plugins/ Oct 12 22:35:22 sibermusic volumio[32389]: info: Loading plugin "system"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "appearance"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "network"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Refreshing Cached IP Addresses Oct 12 22:35:23 sibermusic sudo[32412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 22:35:23 sibermusic sudo[32412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:23 sibermusic sudo[32414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 22:35:23 sibermusic sudo[32414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:23 sibermusic sudo[32414]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:23 sibermusic sudo[32412]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "services"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "volumio5onboarding"... Oct 12 22:35:23 sibermusic sudo[32423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 12 22:35:23 sibermusic sudo[32423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "alsa_controller"... Oct 12 22:35:23 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "wizard"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "networkfs"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Starting Udev Watcher for removable devices Oct 12 22:35:23 sibermusic volumio[32389]: info: Ignoring mount for partition: boot Oct 12 22:35:23 sibermusic volumio[32389]: info: Ignoring mount for partition: volumio Oct 12 22:35:23 sibermusic volumio[32389]: info: Ignoring mount for partition: volumio_data Oct 12 22:35:23 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "volumio_command_line_client"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "upnp"... Oct 12 22:35:23 sibermusic volumio[32389]: info: [1791837323269] Starting Upmpd Daemon Oct 12 22:35:23 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "my_music"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "mpd"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Plugin upnp_browser is not enabled Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "alarm-clock"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "novaui"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Applying required configuration parameters for plugin novaui Oct 12 22:35:23 sibermusic volumio[32389]: info: Plugin airplay_emulation is not enabled Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "last_100"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "webradio"... Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "i2s_dacs"... Oct 12 22:35:23 sibermusic volumio[32389]: info: I2S DAC not set, start Auto-detection Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "volumiodiscovery"... Oct 12 22:35:23 sibermusic volumio[32389]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 22:35:23 sibermusic volumio[32389]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 22:35:23 sibermusic volumio[32389]: *** WARNING *** For more information see Oct 12 22:35:23 sibermusic node[32389]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 22:35:23 sibermusic volumio[32389]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 22:35:23 sibermusic volumio[32389]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 22:35:23 sibermusic volumio[32389]: *** WARNING *** For more information see Oct 12 22:35:23 sibermusic node[32389]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 22:35:23 sibermusic node[32389]: *** WARNING *** For more information see Oct 12 22:35:23 sibermusic node[32389]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 22:35:23 sibermusic node[32389]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 22:35:23 sibermusic node[32389]: *** WARNING *** For more information see Oct 12 22:35:23 sibermusic volumio[32389]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 12 22:35:23 sibermusic volumio[32389]: info: Discovery: Started advertising with name: s i b e r m u s i c Oct 12 22:35:23 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 22:35:23 sibermusic volumio[32389]: info: Loading plugin "bandcamp"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "now_playing"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "glass"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "outputs"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "albumart"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Plugin example_plugin is not enabled Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "inputs"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "telemetry"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "updater_comm"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Plugin mpdemulation is not enabled Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "rest_api"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "websocket"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Starting Socket.io Server version 1.7.4 Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "fusiondsp"... Oct 12 22:35:24 sibermusic volumio[32447]: Forking 3 albumart workers Oct 12 22:35:24 sibermusic volumio[32389]: info: Applying required configuration parameters for plugin fusiondsp Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "backup_restore"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Applying required configuration parameters for plugin backup_restore Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "rpi_eeprom_config"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Applying required configuration parameters for plugin rpi_eeprom_config Oct 12 22:35:24 sibermusic volumio[32389]: info: [RpiEepromConfig] Hardware capabilities loaded Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "rpi_eeprom_updater"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Applying required configuration parameters for plugin rpi_eeprom_updater Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "ir_controller"... Oct 12 22:35:24 sibermusic volumio[32389]: info: Applying required configuration parameters for plugin ir_controller Oct 12 22:35:24 sibermusic volumio[32389]: info: Loading plugin "Systeminfo"... Oct 12 22:35:24 sibermusic volumio[32458]: Starting albumart workers Oct 12 22:35:25 sibermusic volumio[32457]: Starting albumart workers Oct 12 22:35:25 sibermusic volumio[32389]: info: Loading plugin "touch_display"... Oct 12 22:35:25 sibermusic volumio[32389]: info: Applying required configuration parameters for plugin touch_display Oct 12 22:35:25 sibermusic volumio[32459]: Starting albumart workers Oct 12 22:35:25 sibermusic volumio[32389]: info: Loading i18n strings for locale en Oct 12 22:35:25 sibermusic volumio[32389]: Updating browse sources language Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::initPlayerControls Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: VolumeController:: Starting alsactl monitor Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: Express server listening on port 3000 Oct 12 22:35:25 sibermusic volumio[32389]: [Metrics] WebUI: 2s 444.28ms Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::resetVolumioState Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::getcurrentVolume Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 22:35:25 sibermusic volumio[32389]: info: Volumio Network Manager: Network status updated: 1 Oct 12 22:35:25 sibermusic volumio[32389]: verbose: New Socket.io Connection to 192.168.68.101:3000 from 192.168.68.100 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 12 22:35:25 sibermusic volumio[32389]: 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: 2 Oct 12 22:35:25 sibermusic volumio[32389]: info: VolumeController:: Volume=86 Mute =false Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::pushState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioPushState Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::updateTrackBlock Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrackBlock Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 22:35:25 sibermusic volumio[32389]: info: Reloading queue from file Oct 12 22:35:25 sibermusic volumio[32389]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::setRepeat false single undefined Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::pushState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioPushState Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::setRandom false Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::pushState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioPushState Oct 12 22:35:25 sibermusic volumio[32389]: info: Setting Device type: Raspberry PI Oct 12 22:35:25 sibermusic volumio[32389]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 12 22:35:25 sibermusic volumio[32389]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Oct 12 22:35:25 sibermusic volumio[32389]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 12 22:35:25 sibermusic volumio[32389]: verbose: New Socket.io Connection to 192.168.68.101 from 192.168.68.107 UA: Mozilla/5.0 (Linux; Android 17; Pixel 8a Build/CP3A.261005.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/155.0.8059.43 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: websocket Total Clients: 3 Oct 12 22:35:25 sibermusic volumio[32389]: info: VolumeController:: Volume=86 Mute =false Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::pushState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioPushState Oct 12 22:35:25 sibermusic volumio[32389]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 12 22:35:25 sibermusic volumio[32389]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'has') Oct 12 22:35:25 sibermusic volumio[32389]: verbose: New Socket.io Connection to 192.168.68.101:3000 from 192.168.68.100 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 12 22:35:25 sibermusic volumio[32389]: info: Listing playlists Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetQueue Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::getQueue Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getQueue Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 12 22:35:25 sibermusic volumio[32389]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'has') Oct 12 22:35:25 sibermusic volumio[32389]: info: Listing playlists Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetQueue Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::getQueue Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getQueue Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: adding 92366506-d237-4bb1-b027-968fc77348ed Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: Found device s i b e r u p Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: Connecting to remote: 192.168.68.100 Oct 12 22:35:25 sibermusic volumio[32389]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 12 22:35:25 sibermusic volumio[32389]: info: Completed loading Core Plugins Oct 12 22:35:25 sibermusic volumio[32389]: info: Preparing to generate the ALSA configuration file Oct 12 22:35:25 sibermusic volumio[32389]: 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: 7 Oct 12 22:35:25 sibermusic volumio[32389]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 12 22:35:25 sibermusic volumio[32389]: info: Reading ALSA contributions from plugins. Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: adding 31d74d52-4879-45a1-a551-2c6a0819a8cb Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: Found device s i b e r m u s i c Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: this is already registered, 31d74d52-4879-45a1-a551-2c6a0819a8cb Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: Found device s i b e r m u s i c Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: 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: 7 Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: Connected to remote: 192.168.68.100 Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: this is already registered, 31d74d52-4879-45a1-a551-2c6a0819a8cb Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: Found device s i b e r m u s i c Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 22:35:25 sibermusic volumio[32389]: info: CURURI: albums:// Oct 12 22:35:25 sibermusic volumio[32389]: info: Received Get System Version Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 22:35:25 sibermusic volumio[32389]: info: Received Get System Info Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:35:25 sibermusic volumio[32389]: info: Discovery: Getting this device information Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 12 22:35:25 sibermusic sudo[32526]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 12 22:35:25 sibermusic sudo[32531]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 12 22:35:25 sibermusic sudo[32531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:25 sibermusic sudo[32526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:25 sibermusic sudo[32531]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:25 sibermusic sudo[32541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 12 22:35:25 sibermusic sudo[32541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:25 sibermusic volumio[32389]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 12 22:35:25 sibermusic sudo[32526]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 12 22:35:25 sibermusic sudo[32541]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:25 sibermusic sudo[32537]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 12 22:35:25 sibermusic sudo[32537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetState Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getTrack 0 Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreCommandRouter::volumioGetQueue Oct 12 22:35:25 sibermusic volumio[32389]: info: CoreStateMachine::getQueue Oct 12 22:35:25 sibermusic volumio[32389]: info: CorePlayQueue::getQueue Oct 12 22:35:25 sibermusic sudo[32537]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:25 sibermusic volumio[32389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 22:35:25 sibermusic sudo[32551]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 22:35:25 sibermusic sudo[32551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:25 sibermusic volumio[32389]: TypeError: Cannot read properties of undefined (reading 'sendCommand') Oct 12 22:35:25 sibermusic volumio[32389]: at /volumio/app/plugins/music_service/mpd/index.js:3000:22 Oct 12 22:35:25 sibermusic volumio[32389]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 12 22:35:25 sibermusic volumio[32389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 22:35:25 sibermusic sudo[32551]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:25 sibermusic sudo[32547]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 22:35:25 sibermusic sudo[32547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 12 22:35:25 sibermusic sudo[32547]: pam_unix(sudo:session): session closed for user root Oct 12 22:35:25 sibermusic sudo[32571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-12 22:34' Oct 12 22:35:25 sibermusic sudo[32571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="976b86a0f4b6665e5d00be3942489f2a9db63ec5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="865f53b4d15bf520d4a29b69a9510ae0fd92a621" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 9 15:35:55 UTC 2026" VOLUMIO_VERSION="4.225" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="93067a94655014bf8461306f30b390da"