Aug 28 22:39:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:00.141+02:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=h5TzO3yDQociEbSEsxEt6Y6n4Kw1 tokenExpiry=2026-08-28T23:39:00.141+02:00 Aug 28 22:39:00 volumio volumio[1068]: info: Successfully Added MyVolumio device Aug 28 22:39:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:00.306+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.109:57226 Aug 28 22:39:00 volumio volumio[1068]: info: Updating MyVolumio device info Aug 28 22:39:00 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 22:39:00 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 22:39:00 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 22:39:00 volumio volumio[1068]: info: Successfully Updated MyVolumio device Aug 28 22:39:00 volumio volumio[1068]: info: Updating MyVolumio device info Aug 28 22:39:00 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 22:39:00 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 22:39:00 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 22:39:01 volumio volumio[1068]: info: Successfully Updated MyVolumio device Aug 28 22:39:01 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:01.456+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" userId=h5TzO3yDQociEbSEsxEt6Y6n4Kw1 Aug 28 22:39:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 22:39:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 22:39:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 28 22:39:01 volumio volumio[1068]: info: TidalConnect service started! Aug 28 22:39:01 volumio volumio[1068]: [Metrics] CommandRouter: 22s 842.13ms Aug 28 22:39:01 volumio volumio[1068]: info: CoreCommandRouter::volumiosetStartupVolume Aug 28 22:39:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 22:39:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 22:39:01 volumio volumio[1068]: info: CoreCommandRouter::Close All Modals sent Aug 28 22:39:01 volumio volumio[1068]: info: CoreCommandRouter::Close All Modals sent Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.022+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.022+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" providers=9 Aug 28 22:39:02 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 22:39:02 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 22:39:02 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.389+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" plugins=53 Aug 28 22:39:02 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Aug 28 22:39:02 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.390+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_STOPPED positionMs=0 volume=100 Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.390+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id=https://dancewave.online/dance.flac.ogg.pls title="DanceWave losless pls audacious" Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.475+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s Aug 28 22:39:02 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 28 22:39:02 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 22:39:02 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.572+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=97.391397ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.612+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=137.032333ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.619+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=http://pushupdates.volumio.org duration=142.975946ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.625+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=150.38558ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.644+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=168.309457ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.667+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://functions.volumio.cloud duration=191.618106ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.676+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://functions.volumio.cloud duration=199.992105ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.699+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://www.googleapis.com duration=223.755406ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.727+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://securetoken.googleapis.com duration=251.858929ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.903+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://database.volumio.cloud duration=426.787935ms Aug 28 22:39:02 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:02.971+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=http://cddb.volumio.org duration=495.403479ms Aug 28 22:39:03 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:03.187+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=http://plugins.volumio.org duration=711.391664ms Aug 28 22:39:03 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:03.369+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=147.463447ms timeout=10s endpoint=https://google.com duration=893.888115ms Aug 28 22:39:05 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 22:39:06 volumio volumio[1068]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 22:39:06 volumio volumio[1068]: info: Received Get System Version Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 22:39:06 volumio volumio[1068]: info: Received Get System Info Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 22:39:06 volumio volumio[1068]: info: Discovery: Getting this device information Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Aug 28 22:39:06 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 22:39:06 volumio volumio[1068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 28 22:39:06 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Aug 28 22:39:06 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:08 volumio volumio[1068]: info: BOOT COMPLETED Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 22:39:11 volumio volumio-remote-updater[755]: Test mode disabled Aug 28 22:39:11 volumio volumio-remote-updater[755]: Alpha mode disabled Aug 28 22:39:11 volumio volumio-remote-updater[755]: Alpha legacy test mode disabled Aug 28 22:39:11 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 28 22:39:12 volumio volumio[1068]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 28 22:39:12 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 28 22:39:12 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 28 22:39:15 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:15.658+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.109:53688 Aug 28 22:39:25 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.704+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.744+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=39.600289ms Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.750+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=45.484554ms Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.755+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=http://pushupdates.volumio.org duration=50.368764ms Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.761+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=55.538783ms Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.827+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://www.googleapis.com duration=122.235194ms Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.868+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://securetoken.googleapis.com duration=163.325991ms Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.882+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=176.751941ms Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.913+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://functions.volumio.cloud duration=208.803778ms Aug 28 22:39:28 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:28.948+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://functions.volumio.cloud duration=242.989656ms Aug 28 22:39:29 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 22:39:29 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 22:39:29 volumio volumio[1068]: info: Discovery: Getting this device information Aug 28 22:39:29 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Aug 28 22:39:29 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:29 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 22:39:29 volumio volumio[1068]: verbose: New Socket.io Connection to 192.168.0.5:3000 from 192.168.0.109 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 28 22:39:29 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 22:39:29 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 22:39:29 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:29.164+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://database.volumio.cloud duration=458.8949ms Aug 28 22:39:29 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:29.422+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=https://google.com duration=717.527323ms Aug 28 22:39:29 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:29.544+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=http://plugins.volumio.org duration=839.212326ms Aug 28 22:39:32 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 28 22:39:32 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 28 22:39:34 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:34.160+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=145.71153ms timeout=10s endpoint=http://cddb.volumio.org duration=5.455517262s Aug 28 22:39:35 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 22:39:35 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 28 22:39:44 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 22:39:54 volumio volumio[1068]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy1 [object Object] Aug 28 22:39:54 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy1 Aug 28 22:39:54 volumio volumio[1068]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Aug 28 22:39:54 volumio volumio[1068]: info: PeppyMeterBasic -----------------------meter01G5_Kenwood Rev Aug 28 22:39:54 volumio volumio[1068]: info: PeppyMeterBasic -----------------------$basefolder/data/INTERNAL/PeppyMeterBasic/Templates Aug 28 22:39:54 volumio volumio[1068]: info: PeppyMeterBasic -----------------------screensize2560x720-Gelo5-BASIC_921 Aug 28 22:39:54 volumio volumio[1068]: info: PeppyMeterBasic -----------------------screenwidth2560 Aug 28 22:39:54 volumio volumio[1068]: info: PeppyMeterBasic -----------------------screenheight720 Aug 28 22:39:54 volumio volumio[1068]: info: PeppyMeterBasic -----------------------metersize30 Aug 28 22:39:54 volumio volumio[1068]: error: PeppyMeterBasic ---Error writing config null Aug 28 22:39:54 volumio sudo[2454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppymeterbasic.service Aug 28 22:39:54 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 22:39:54 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Aug 28 22:39:54 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root Aug 28 22:39:54 volumio volumio[1068]: info: peppymeterbasic Daemon Started Aug 28 22:39:54 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 28 22:39:54 volumio volumio[1068]: info: CoreCommandRouter::Close All Modals sent Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Aug 28 22:39:59 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Aug 28 22:39:59 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:59 volumio volumio[1068]: info: Currently active: webradio Aug 28 22:39:59 volumio volumio[1068]: info: Stopping currently active service Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::volumioStop Aug 28 22:39:59 volumio volumio[1068]: info: CoreStateMachine::stop Aug 28 22:39:59 volumio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 22:39:59 volumio volumio[1068]: info: TidalConnect Active Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Aug 28 22:39:59 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:59 volumio volumio[1068]: info: Currently active: webradio Aug 28 22:39:59 volumio volumio[1068]: info: Setting Volatile state to tidalconnect Aug 28 22:39:59 volumio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:39:59 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:39:59 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:59 volumio volumio[1068]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:39:59 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:39:59 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:39:59 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:39:59 volumio volumio[1068]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Aug 28 22:39:59 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:59.496+02:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 28 22:39:59 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:59.496+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_STOPPED positionMs= volume=100 Aug 28 22:39:59 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:59.496+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title= Aug 28 22:39:59 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:59.496+02:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 28 22:39:59 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:59.496+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_STOPPED positionMs= volume=100 Aug 28 22:39:59 volumio volumio5-onboarding[1493]: time=2026-08-28T22:39:59.496+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title= Aug 28 22:39:59 volumio volumio[1068]: info: Display-configuration --- Volumio status= timeout=120 noifplay=true screensavertype=dpms Aug 28 22:39:59 volumio volumio[1068]: info: Display-configuration --- Volumio status=undefined timeout=120 noifplay=true screensavertype=dpms Aug 28 22:39:59 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status Aug 28 22:39:59 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status undefined Aug 28 22:39:59 volumio volumio[1068]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.h5TzO3yDQociEbSEsxEt6Y6n4Kw1.433d0ff1eed26b81494f116be7cd41a7.state.status' Aug 28 22:39:59 volumio vtcs[2201]: [2026-08-28 22:39:59.863] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:1 Aug 28 22:40:00 volumio volumio[1068]: info: Pushing metadata Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:40:00 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:40:00 volumio volumio[1068]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:40:00 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Aug 28 22:40:00 volumio volumio[1068]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.271+02:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.271+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_STOPPED positionMs= volume=100 Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.271+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title= Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.271+02:00 level=WARN msg="received unknown player status" component=volumio status="" Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.271+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_STOPPED positionMs= volume=100 Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.271+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title= Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.271+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_PAUSED positionMs=6016 volume=100 Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.271+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title="Let Me Breathe" Aug 28 22:40:00 volumio volumio[1068]: info: Display-configuration --- Volumio status=undefined timeout=120 noifplay=true screensavertype=dpms Aug 28 22:40:00 volumio volumio[1068]: info: Display-configuration --- Volumio status=undefined timeout=120 noifplay=true screensavertype=dpms Aug 28 22:40:00 volumio volumio[1068]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 22:40:00 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status undefined Aug 28 22:40:00 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status undefined Aug 28 22:40:00 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 28 22:40:00 volumio volumio[1068]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.h5TzO3yDQociEbSEsxEt6Y6n4Kw1.433d0ff1eed26b81494f116be7cd41a7.state.status' Aug 28 22:40:00 volumio volumio[1068]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.h5TzO3yDQociEbSEsxEt6Y6n4Kw1.433d0ff1eed26b81494f116be7cd41a7.state.status' Aug 28 22:40:00 volumio sudo[2497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 22:40:00 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 22:40:00 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Aug 28 22:40:00 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Aug 28 22:40:00 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Aug 28 22:40:00 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Aug 28 22:40:00 volumio volumio[1068]: info: peppymeterbasic Daemon Stop Aug 28 22:40:00 volumio volumio[1068]: info: Pushing metadata Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.532+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_PAUSED positionMs=6016 volume=100 Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.532+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title="Let Me Breathe" Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.532+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_PAUSED positionMs=6016 volume=100 Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.532+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title="Let Me Breathe" Aug 28 22:40:00 volumio volumio[1068]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 22:40:00 volumio volumio[1068]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 22:40:00 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 28 22:40:00 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 28 22:40:00 volumio sudo[2510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 22:40:00 volumio sudo[2510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 22:40:00 volumio sudo[2512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 22:40:00 volumio sudo[2512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 22:40:00 volumio sudo[2510]: pam_unix(sudo:session): session closed for user root Aug 28 22:40:00 volumio volumio[1068]: info: peppymeterbasic Daemon Stop Aug 28 22:40:00 volumio sudo[2512]: pam_unix(sudo:session): session closed for user root Aug 28 22:40:00 volumio volumio[1068]: info: peppymeterbasic Daemon Stop Aug 28 22:40:00 volumio vtcs[2201]: [close:85] Entering Aug 28 22:40:00 volumio vtcs[2201]: [close:100] Exiting Aug 28 22:40:00 volumio vtcs[2201]: [open:50] Opening with sample rate: 44100, type: 3 Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 22:40:00 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.679+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_PAUSED positionMs=6016 volume=100 Aug 28 22:40:00 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:00.679+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title="Let Me Breathe" Aug 28 22:40:00 volumio volumio[1068]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 22:40:00 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 28 22:40:00 volumio sudo[2516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 22:40:00 volumio sudo[2516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 22:40:00 volumio sudo[2516]: pam_unix(sudo:session): session closed for user root Aug 28 22:40:00 volumio volumio[1068]: info: peppymeterbasic Daemon Stop Aug 28 22:40:01 volumio vtcs[2201]: [2026-08-28 22:40:01.364] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Aug 28 22:40:03 volumio vtcs[2201]: [start:106] Entering Aug 28 22:40:03 volumio vtcs[2201]: [start:113] Reopening PCM device... Aug 28 22:40:03 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Aug 28 22:40:03 volumio volumio[1068]: info: CoreStateMachine::pushState Aug 28 22:40:03 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 22:40:03 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Aug 28 22:40:03 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:03.295+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" state=STATUS_PLAYING positionMs=6015 volume=100 Aug 28 22:40:03 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:03.295+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:50452 @ 0xc000414330" id= title="Let Me Breathe" Aug 28 22:40:03 volumio volumio[1068]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 28 22:40:03 volumio volumio[1068]: info: Display-configuration --- → Wakeup triggered Aug 28 22:40:03 volumio volumio[1068]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 22:40:03 volumio volumio[1068]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 28 22:40:03 volumio sudo[2524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 22:40:03 volumio sudo[2524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 22:40:03 volumio vtcs[2201]: [start:115] PCM opened successfully. Aug 28 22:40:03 volumio vtcs[2201]: [start:120] Exiting Aug 28 22:40:03 volumio vtcs[2201]: [feedThread:223] Entering Aug 28 22:40:03 volumio vtcs[2201]: vtcs: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Aug 28 22:40:03 volumio vtcs[2200]: Aborted Aug 28 22:40:03 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Aug 28 22:40:03 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=134/n/a Aug 28 22:40:03 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. Aug 28 22:40:03 volumio sudo[2524]: pam_unix(sudo:session): session closed for user root Aug 28 22:40:03 volumio volumio[1068]: info: peppymeterbasic Daemon Started Aug 28 22:40:03 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Aug 28 22:40:03 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:03 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:03 volumio volumio[1068]: info: Executing endpoint tc_getconfig Aug 28 22:40:03 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 22:40:03 volumio vtcs[2531]: BYE TidalConnect Aug 28 22:40:03 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 22:40:03 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Aug 28 22:40:03 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:03 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:03 volumio volumio[1068]: info: Executing endpoint tc_getconfig Aug 28 22:40:03 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 22:40:03 volumio vtcs[2534]: BYE TidalConnect Aug 28 22:40:03 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Aug 28 22:40:04 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:04 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:04 volumio volumio[1068]: info: Executing endpoint tc_getconfig Aug 28 22:40:04 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 22:40:04 volumio vtcs[2536]: BYE TidalConnect Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Aug 28 22:40:04 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:04 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:04 volumio volumio[1068]: info: Executing endpoint tc_getconfig Aug 28 22:40:04 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 22:40:04 volumio vtcs[2539]: BYE TidalConnect Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. Aug 28 22:40:04 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:04 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:04 volumio volumio[1068]: info: Executing endpoint tc_getconfig Aug 28 22:40:04 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 22:40:04 volumio vtcs[2550]: BYE TidalConnect Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 6. Aug 28 22:40:04 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Start request repeated too quickly. Aug 28 22:40:04 volumio systemd[1]: vtcs.service: Failed with result 'start-limit-hit'. Aug 28 22:40:04 volumio systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.063+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.090+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=27.204241ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.106+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=http://pushupdates.volumio.org duration=42.385216ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.106+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=42.268108ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.113+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=49.777726ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.186+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://www.googleapis.com duration=123.076813ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.221+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://securetoken.googleapis.com duration=157.992575ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.236+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=172.927324ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.261+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://functions.volumio.cloud duration=197.774979ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.261+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://functions.volumio.cloud duration=197.829651ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.517+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=http://cddb.volumio.org duration=453.66577ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.653+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://database.volumio.cloud duration=589.427072ms Aug 28 22:40:18 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:18.696+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=http://plugins.volumio.org duration=633.233066ms Aug 28 22:40:19 volumio volumio5-onboarding[1493]: time=2026-08-28T22:40:19.008+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:50452 @ 0xc000414330" latency=142.386621ms timeout=10s endpoint=https://google.com duration=944.506902ms Aug 28 22:40:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 22:40:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 22:40:19 volumio volumio[1068]: info: Discovery: Getting this device information Aug 28 22:40:19 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Aug 28 22:40:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 22:40:19 volumio volumio[1068]: verbose: New Socket.io Connection to 192.168.0.5:3000 from 192.168.0.109 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Aug 28 22:40:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 22:40:19 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 22:40:22 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 22:40:22 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 28 22:40:28 volumio volumio[1068]: info: Stopping peppymeterbasic service Aug 28 22:40:28 volumio volumio[1068]: info: CoreStateMachine::stop Aug 28 22:40:28 volumio volumio[1068]: info: CoreStateMachine::serviceStop Aug 28 22:40:28 volumio volumio[1068]: info: CoreCommandRouter::serviceStop Aug 28 22:40:28 volumio volumio[1068]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 22:40:28 volumio volumio[1068]: TypeError: Cannot read properties of undefined (reading 'then') Aug 28 22:40:28 volumio volumio[1068]: at peppymeterbasic.onStop (/data/plugins/user_interface/peppymeterbasic/index.js:58:43) Aug 28 22:40:28 volumio volumio[1068]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 28 22:40:28 volumio volumio[1068]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Aug 28 22:40:28 volumio volumio[1068]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Aug 28 22:40:28 volumio volumio[1068]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Aug 28 22:40:28 volumio volumio[1068]: at Socket.emit (node:events:514:28) Aug 28 22:40:28 volumio volumio[1068]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 28 22:40:28 volumio volumio[1068]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 28 22:40:28 volumio volumio[1068]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 22:40:28 volumio sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 22:39' Aug 28 22:40:28 volumio sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"