Oct 14 11:58:04 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:04 sibermusic volumio[10110]: info: CoreCommandRouter::Close All Modals sent Oct 14 11:58:12 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:20 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.470+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.286278042s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.501+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.524+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=22.058866ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.529+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=27.491577ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.531+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=http://pushupdates.volumio.org duration=29.499894ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.533+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=32.018806ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.563+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://google.com duration=61.923627ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.625+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://www.googleapis.com duration=124.007106ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.640+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=138.798609ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.654+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://functions.volumio.cloud duration=152.474999ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.654+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://functions.volumio.cloud duration=152.668796ms Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.660+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://database.volumio.cloud duration=157.839155ms Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 11:58:23 sibermusic volumio[10110]: info: Discovery: Getting this device information Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:58:23 sibermusic volumio[10110]: 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: 11 Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetQueue Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreStateMachine::getQueue Oct 14 11:58:23 sibermusic volumio[10110]: info: CorePlayQueue::getQueue Oct 14 11:58:23 sibermusic volumio[10110]: info: Received Get System Version Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 11:58:23 sibermusic volumio[10110]: info: Received Get System Info Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 11:58:23 sibermusic volumio[10110]: info: Discovery: Getting this device information Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 14 11:58:23 sibermusic volumio[10110]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 14 11:58:23 sibermusic sudo[15518]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 14 11:58:23 sibermusic sudo[15518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:58:23 sibermusic sudo[15518]: pam_unix(sudo:session): session closed for user root Oct 14 11:58:23 sibermusic sudo[15523]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 14 11:58:23 sibermusic sudo[15523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:58:23 sibermusic sudo[15523]: pam_unix(sudo:session): session closed for user root Oct 14 11:58:23 sibermusic sudo[15532]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 14 11:58:23 sibermusic sudo[15532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:58:23 sibermusic sudo[15536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 14 11:58:23 sibermusic sudo[15536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:58:23 sibermusic sudo[15532]: pam_unix(sudo:session): session closed for user root Oct 14 11:58:23 sibermusic sudo[15536]: pam_unix(sudo:session): session closed for user root Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 14 11:58:23 sibermusic sudo[15541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 11:58:23 sibermusic sudo[15541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:58:23 sibermusic sudo[15541]: pam_unix(sudo:session): session closed for user root Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 14 11:58:23 sibermusic sudo[15546]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 11:58:23 sibermusic sudo[15546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetQueue Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreStateMachine::getQueue Oct 14 11:58:23 sibermusic volumio[10110]: info: CorePlayQueue::getQueue Oct 14 11:58:23 sibermusic volumio[10110]: info: Received Get System Version Oct 14 11:58:23 sibermusic sudo[15546]: pam_unix(sudo:session): session closed for user root Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 11:58:23 sibermusic volumio[10110]: info: Received Get System Info Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 11:58:23 sibermusic volumio[10110]: info: Discovery: Getting this device information Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 14 11:58:23 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.945+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=http://cddb.volumio.org duration=442.942181ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 11:58:23 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:23.967+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=http://plugins.volumio.org duration=465.467344ms Oct 14 11:58:24 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:24.129+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.107:52202 @ 0x314cf90" latency=-1.29018331s timeout=10s endpoint=https://securetoken.googleapis.com duration=627.556523ms Oct 14 11:58:24 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 11:58:26 sibermusic volumio[10110]: info: Received Get System Version Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 11:58:26 sibermusic volumio[10110]: info: Received Get System Info Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 11:58:26 sibermusic volumio[10110]: info: Discovery: Getting this device information Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:58:26 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 14 11:58:29 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:58:29 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:58:29 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 11:58:37 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:39 sibermusic volumio[10110]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/bookworm/armhf Oct 14 11:58:39 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:39.223+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:41 sibermusic volumio[10110]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/now_playing/volumio/bookworm/armhf Oct 14 11:58:41 sibermusic volumio[10110]: info: Folder /tmp/plugins removed Oct 14 11:58:41 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:41.269+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:41 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:41.670+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:41 sibermusic volumio[10110]: info: Check plugin dependencies Oct 14 11:58:41 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 11:58:41 sibermusic volumio[10110]: info: Checking if plugin already exists Oct 14 11:58:41 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:41.692+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:41 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:41.694+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:41 sibermusic volumio[10110]: info: Rename folder Oct 14 11:58:41 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:41.701+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:41 sibermusic volumio[10110]: info: Move to category Oct 14 11:58:41 sibermusic volumio[10110]: info: Folder /tmp/downloaded_plugin.zip removed Oct 14 11:58:41 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:41.863+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:41 sibermusic volumio[10110]: info: Checking if install.sh is present Oct 14 11:58:41 sibermusic volumio[10110]: info: Executing install.sh Oct 14 11:58:41 sibermusic sudo[15662]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Oct 14 11:58:41 sibermusic sudo[15662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:58:41 sibermusic volumio[10110]: info: Installing geo-tz node dependency Oct 14 11:58:41 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:41.881+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:41 sibermusic su[15665]: (to volumio) root on none Oct 14 11:58:41 sibermusic su[15665]: pam_unix(su:session): session opened for user volumio(uid=1000) by (uid=0) Oct 14 11:58:41 sibermusic systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Oct 14 11:58:41 sibermusic systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 14 11:58:41 sibermusic systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 14 11:58:41 sibermusic systemd[1]: Starting user@1000.service - User Manager for UID 1000... Oct 14 11:58:41 sibermusic (systemd)[15667]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Oct 14 11:58:42 sibermusic systemd[15667]: Queued start job for default target default.target. Oct 14 11:58:42 sibermusic systemd[15667]: Created slice app.slice - User Application Slice. Oct 14 11:58:42 sibermusic systemd[15667]: Reached target paths.target - Paths. Oct 14 11:58:42 sibermusic systemd[15667]: Reached target timers.target - Timers. Oct 14 11:58:42 sibermusic systemd[15667]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 14 11:58:42 sibermusic systemd[15667]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Oct 14 11:58:42 sibermusic systemd[15667]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 14 11:58:42 sibermusic systemd[15667]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 14 11:58:42 sibermusic systemd[15667]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 14 11:58:42 sibermusic systemd[15667]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 14 11:58:42 sibermusic systemd[15667]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 14 11:58:42 sibermusic systemd[15667]: Reached target sockets.target - Sockets. Oct 14 11:58:42 sibermusic systemd[15667]: Reached target basic.target - Basic System. Oct 14 11:58:42 sibermusic systemd[1]: Started user@1000.service - User Manager for UID 1000. Oct 14 11:58:42 sibermusic systemd[15667]: Started mpris-proxy.service - Bluetooth mpris proxy. Oct 14 11:58:42 sibermusic systemd[15667]: Reached target default.target - Main User Target. Oct 14 11:58:42 sibermusic systemd[15667]: Startup finished in 264ms. Oct 14 11:58:42 sibermusic systemd[1]: Started session-c1.scope - Session c1 of User volumio. Oct 14 11:58:42 sibermusic systemd[15667]: Created slice session.slice - User Core Session Slice. Oct 14 11:58:42 sibermusic systemd[15667]: Starting dbus.service - D-Bus User Message Bus... Oct 14 11:58:42 sibermusic systemd[15667]: Started dbus.service - D-Bus User Message Bus. Oct 14 11:58:47 sibermusic volumio[10110]: info: Oct 14 11:58:47 sibermusic volumio[10110]: info: added 27 packages, changed 4 packages, and audited 162 packages in 5s Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.247+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.247+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio[10110]: info: Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.250+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio[10110]: info: 24 packages are looking for funding Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.251+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio[10110]: info: run `npm fund` for details Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.261+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio[10110]: info: Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.262+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio[10110]: info: 12 vulnerabilities (4 moderate, 7 high, 1 critical) Oct 14 11:58:47 sibermusic volumio[10110]: info: Oct 14 11:58:47 sibermusic volumio[10110]: info: To address issues that do not require attention, run: Oct 14 11:58:47 sibermusic volumio[10110]: info: npm audit fix Oct 14 11:58:47 sibermusic volumio[10110]: info: Oct 14 11:58:47 sibermusic volumio[10110]: info: To address all issues (including breaking changes), run: Oct 14 11:58:47 sibermusic volumio[10110]: info: npm audit fix --force Oct 14 11:58:47 sibermusic volumio[10110]: info: Oct 14 11:58:47 sibermusic volumio[10110]: info: Run `npm audit` for details. Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic su[15665]: pam_unix(su:session): session closed for user volumio Oct 14 11:58:47 sibermusic systemd[1]: session-c1.scope: Deactivated successfully. Oct 14 11:58:47 sibermusic systemd[1]: session-c1.scope: Consumed 9.655s CPU time. Oct 14 11:58:47 sibermusic volumio[10110]: info: Creating user directories (if not exist) Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.286+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic sudo[15662]: pam_unix(sudo:session): session closed for user root Oct 14 11:58:47 sibermusic volumio[10110]: info: Now Playing plugin installed Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.293+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio[10110]: Plugin install end detected on script Oct 14 11:58:47 sibermusic volumio[10110]: info: Install script completed Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.300+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio[10110]: info: Adding reference to registry Oct 14 11:58:47 sibermusic volumio5-onboarding[1765]: time=2026-10-14T11:58:47.302+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 11:58:47 sibermusic volumio[10110]: info: Done installing plugin. Oct 14 11:58:47 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:58:47 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 11:58:47 sibermusic volumio[10110]: info: Folder /tmp/plugins removed Oct 14 11:58:47 sibermusic volumio[10110]: info: Folder /tmp/downloaded_plugin.zip removed Oct 14 11:58:47 sibermusic volumio[10110]: info: Folder /data/temp removed Oct 14 11:58:47 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 11:58:47 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 11:58:55 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:58:56 sibermusic volumio[10110]: info: Enabling plugin now_playing Oct 14 11:58:56 sibermusic volumio[10110]: info: Loading plugin "now_playing"... Oct 14 11:58:56 sibermusic volumio[10110]: info: PLUGIN START: now_playing Oct 14 11:58:56 sibermusic volumio[10110]: info: [now-playing] ConfigUpdater: config version unavailable. Either this is the first time the plugin is installed, or the previous version is < 0.3.0). Config will not be updated. Oct 14 11:58:56 sibermusic volumio[10110]: info: [now-playing] ConfigUpdater: updated config version to 1.0.6 Oct 14 11:58:56 sibermusic volumio[10110]: info: [now-playing] App is listening on port 4004. Oct 14 11:58:56 sibermusic volumio[10110]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 14 11:58:56 sibermusic volumio[10110]: info: Done. Oct 14 11:58:56 sibermusic volumio[10110]: info: CoreCommandRouter::Close All Modals sent Oct 14 11:58:57 sibermusic systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Oct 14 11:58:57 sibermusic systemd[15667]: Activating special unit exit.target... Oct 14 11:58:57 sibermusic systemd[15667]: Stopped target default.target - Main User Target. Oct 14 11:58:57 sibermusic systemd[15667]: Stopping mpris-proxy.service - Bluetooth mpris proxy... Oct 14 11:58:57 sibermusic mpris-proxy[15683]: org.bluez appeared Oct 14 11:58:57 sibermusic mpris-proxy[15683]: Bluetooth Adapter /org/bluez/hci0 found Oct 14 11:58:57 sibermusic mpris-proxy[15683]: Bluetooth Adapter /org/bluez/hci0 removed Oct 14 11:58:57 sibermusic mpris-proxy[15683]: org.bluez disappeared Oct 14 11:58:57 sibermusic systemd[15667]: Stopped mpris-proxy.service - Bluetooth mpris proxy. Oct 14 11:58:57 sibermusic systemd[15667]: Stopping dbus.service - D-Bus User Message Bus... Oct 14 11:58:57 sibermusic systemd[15667]: Stopped dbus.service - D-Bus User Message Bus. Oct 14 11:58:57 sibermusic systemd[15667]: Removed slice session.slice - User Core Session Slice. Oct 14 11:58:57 sibermusic systemd[15667]: Stopped target basic.target - Basic System. Oct 14 11:58:57 sibermusic systemd[15667]: Stopped target paths.target - Paths. Oct 14 11:58:57 sibermusic systemd[15667]: Stopped target sockets.target - Sockets. Oct 14 11:58:57 sibermusic systemd[15667]: Stopped target timers.target - Timers. Oct 14 11:58:57 sibermusic systemd[15667]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 14 11:58:57 sibermusic systemd[15667]: Closed dirmngr.socket - GnuPG network certificate management daemon. Oct 14 11:58:57 sibermusic systemd[15667]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 14 11:58:57 sibermusic systemd[15667]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 14 11:58:57 sibermusic systemd[15667]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 14 11:58:57 sibermusic systemd[15667]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 14 11:58:57 sibermusic systemd[15667]: Removed slice app.slice - User Application Slice. Oct 14 11:58:57 sibermusic systemd[15667]: Reached target shutdown.target - Shutdown. Oct 14 11:58:57 sibermusic systemd[15667]: Finished systemd-exit.service - Exit the Session. Oct 14 11:58:57 sibermusic systemd[15667]: Reached target exit.target - Exit the Session. Oct 14 11:58:57 sibermusic systemd[1]: user@1000.service: Deactivated successfully. Oct 14 11:58:57 sibermusic systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Oct 14 11:58:57 sibermusic systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 14 11:58:57 sibermusic systemd[1]: run-user-1000.mount: Deactivated successfully. Oct 14 11:58:57 sibermusic systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Oct 14 11:58:57 sibermusic systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 14 11:58:57 sibermusic systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Oct 14 11:58:57 sibermusic systemd[1]: user-1000.slice: Consumed 9.929s CPU time. Oct 14 11:59:03 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:59:11 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:59:16 sibermusic volumio[10110]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 11:59:16 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 11:59:16 sibermusic volumio[10110]: info: Discovery: Getting this device information Oct 14 11:59:16 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:59:16 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 11:59:23 sibermusic volumio[10110]: info: CoreCommandRouter::volumioGetState Oct 14 11:59:24 sibermusic volumio[10110]: info: CALLMETHOD: user_interface now_playing configRestoreConfigFromBackup [object Object] Oct 14 11:59:24 sibermusic volumio[10110]: info: CoreCommandRouter::executeOnPlugin: now_playing , configRestoreConfigFromBackup Oct 14 11:59:24 sibermusic volumio[10110]: info: [now-playing] ConfigUpdater: config is up to date. Oct 14 11:59:24 sibermusic volumio[10110]: warn: [now-playing] MyBackgroundMonitor stopped Oct 14 11:59:24 sibermusic volumio[10110]: info: [now-playing] ConfigUpdater: config version is newer than plugin version (1.1.1 > 1.0.6). Config will not be updated. Oct 14 11:59:24 sibermusic volumio[10110]: info: [now-playing] ConfigUpdater: updated config version to 1.0.6 Oct 14 11:59:24 sibermusic volumio[10110]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): OpenWeatherMap API key not configured. Add it in Weather Service settings. Oct 14 11:59:24 sibermusic volumio[10110]: info: [now-playing] App is listening on port 4004. Oct 14 11:59:24 sibermusic volumio[10110]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Oct 14 11:59:24 sibermusic sudo[15967]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Oct 14 11:59:24 sibermusic sudo[15967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:59:24 sibermusic sudo[15967]: pam_unix(sudo:session): session closed for user root Oct 14 11:59:24 sibermusic volumio[10110]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Oct 14 11:59:24 sibermusic sudo[15970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Oct 14 11:59:24 sibermusic sudo[15970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 11:59:24 sibermusic sudo[15970]: pam_unix(sudo:session): session closed for user root Oct 14 11:59:24 sibermusic volumio[10110]: error: [now-playing] Failed to execute systemctl command status on volumio-kiosk: Error: Command failed: /usr/bin/sudo /bin/systemctl status volumio-kiosk Oct 14 11:59:24 sibermusic volumio[10110]: at ChildProcess.exithandler (node:child_process:421:12) Oct 14 11:59:24 sibermusic volumio[10110]: at ChildProcess.emit (node:events:514:28) Oct 14 11:59:24 sibermusic volumio[10110]: at maybeClose (node:internal/child_process:1105:16) Oct 14 11:59:24 sibermusic volumio[10110]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Oct 14 11:59:24 sibermusic volumio[10110]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 11:59:24 sibermusic volumio[10110]: Error: Command failed: /usr/bin/sudo /bin/systemctl status volumio-kiosk Oct 14 11:59:24 sibermusic volumio[10110]: at ChildProcess.exithandler (node:child_process:421:12) Oct 14 11:59:24 sibermusic volumio[10110]: at ChildProcess.emit (node:events:514:28) Oct 14 11:59:24 sibermusic volumio[10110]: at maybeClose (node:internal/child_process:1105:16) Oct 14 11:59:24 sibermusic volumio[10110]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Oct 14 11:59:24 sibermusic volumio[10110]: code: 3, Oct 14 11:59:24 sibermusic volumio[10110]: killed: false, Oct 14 11:59:24 sibermusic volumio[10110]: signal: null, Oct 14 11:59:24 sibermusic volumio[10110]: cmd: '/usr/bin/sudo /bin/systemctl status volumio-kiosk' Oct 14 11:59:24 sibermusic volumio[10110]: } Oct 14 11:59:24 sibermusic volumio[10110]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 11:59:24 sibermusic sudo[15986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 11:58' Oct 14 11:59:24 sibermusic sudo[15986]: 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"