Aug 28 15:59:12 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 28 15:59:12 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 28 15:59:12 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 28 15:59:22 volumio volumio[1089]: verbose: New Socket.io Connection to 192.168.0.5 from 192.168.0.109 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/152.0.7977.64 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 28 15:59:22 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 28 15:59:22 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 15:59:22 volumio volumio[1089]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 15:59:22 volumio volumio[1089]: info: Listing playlists Aug 28 15:59:22 volumio volumio[1089]: info: CoreCommandRouter::volumioGetQueue Aug 28 15:59:22 volumio volumio[1089]: info: CoreStateMachine::getQueue Aug 28 15:59:22 volumio volumio[1089]: info: CorePlayQueue::getQueue Aug 28 15:59:22 volumio volumio[1089]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 15:59:22 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.149+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.288+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=http://pushupdates.volumio.org duration=137.766098ms Aug 28 15:59:39 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 15:59:39 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 15:59:39 volumio volumio[1089]: info: Discovery: Getting this device information Aug 28 15:59:39 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 28 15:59:39 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 15:59:39 volumio volumio[1089]: 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 15:59:39 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 15:59:39 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.364+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://www.googleapis.com duration=213.846149ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.420+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://securetoken.googleapis.com duration=270.079883ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.541+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=391.698911ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.612+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=461.761172ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.624+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=474.4315ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.640+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=489.559133ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.711+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=http://cddb.volumio.org duration=560.51874ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.862+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://google.com duration=712.0438ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.870+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://database.volumio.cloud duration=720.09832ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.959+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://functions.volumio.cloud duration=808.765462ms Aug 28 15:59:39 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:39.959+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=https://functions.volumio.cloud duration=809.05435ms Aug 28 15:59:42 volumio volumio[1089]: info: CoreCommandRouter::volumioStop Aug 28 15:59:42 volumio volumio[1089]: info: CoreStateMachine::stop Aug 28 15:59:42 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 15:59:42 volumio volumio[1089]: info: CoreStateMachine::stPlaybackTimer Aug 28 15:59:42 volumio volumio[1089]: info: CoreStateMachine::updateTrackBlock Aug 28 15:59:42 volumio volumio[1089]: info: CorePlayQueue::getTrackBlock Aug 28 15:59:42 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:42 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 28 15:59:42 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 15:59:42 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:42 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:42 volumio volumio[1089]: info: CoreStateMachine::serviceStop Aug 28 15:59:42 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 28 15:59:42 volumio volumio[1089]: info: CoreCommandRouter::serviceStop Aug 28 15:59:42 volumio volumio[1089]: info: [1787925582791] ControllerWebradio::stop Aug 28 15:59:42 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 15:59:42 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:42.791+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_STOPPED positionMs=0 volume=100 Aug 28 15:59:42 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:42.792+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id=https://dancewave.online/dance.flac.ogg.pls title="DanceWave losless pls audacious" Aug 28 15:59:42 volumio volumio[1089]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:42 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 15:59:42 volumio sudo[23911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 15:59:42 volumio volumio[1089]: info: Aug 28 15:59:42 volumio volumio[1089]: ---------------------------- MPD announces state update: player Aug 28 15:59:42 volumio volumio[1089]: info: sendMpdCommand stop took 18 milliseconds Aug 28 15:59:42 volumio volumio[1089]: info: ControllerMpd::getState Aug 28 15:59:42 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Aug 28 15:59:42 volumio volumio[1089]: info: sendMpdCommand status took 1 milliseconds Aug 28 15:59:42 volumio volumio[1089]: verbose: ControllerMpd::parseState Aug 28 15:59:42 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 15:59:42 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 28 15:59:42 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Aug 28 15:59:42 volumio volumio[1089]: info: ControllerMpd::pushState Aug 28 15:59:42 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:42 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:42 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 28 15:59:42 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:42 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:42 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 28 15:59:42 volumio volumio[1089]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 28 15:59:42 volumio sudo[23911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:42 volumio volumio[1089]: info: ------------------------------ 6ms Aug 28 15:59:42 volumio volumio[1089]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:42 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:42.814+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_STOPPED positionMs=0 volume=100 Aug 28 15:59:42 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:42.814+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id=https://dancewave.online/dance.flac.ogg.pls title="DanceWave losless pls audacious" Aug 28 15:59:42 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 15:59:42 volumio sudo[23919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 15:59:42 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Aug 28 15:59:42 volumio sudo[23919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:42 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Aug 28 15:59:42 volumio systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Aug 28 15:59:42 volumio systemd[1]: peppyspectrum.service: Consumed 39.702s CPU time. Aug 28 15:59:42 volumio sudo[23911]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:42 volumio volumio[1089]: info: peppyspectrum Daemon Stop Aug 28 15:59:42 volumio sudo[23919]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:42 volumio volumio[1089]: info: peppyspectrum Daemon Stop Aug 28 15:59:45 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:45.267+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.74595ms timeout=10s endpoint=http://plugins.volumio.org duration=6.116970294s Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 28 15:59:50 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 28 15:59:50 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 28 15:59:50 volumio volumio[1089]: info: Currently active: webradio Aug 28 15:59:50 volumio volumio[1089]: info: Stopping currently active service Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::volumioStop Aug 28 15:59:50 volumio volumio[1089]: info: CoreStateMachine::stop Aug 28 15:59:50 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 15:59:50 volumio volumio[1089]: info: TidalConnect Active Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 28 15:59:50 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 28 15:59:50 volumio volumio[1089]: info: Currently active: webradio Aug 28 15:59:50 volumio volumio[1089]: info: Setting Volatile state to tidalconnect Aug 28 15:59:50 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:50 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:50 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:50 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:50 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:50 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:50 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:50.270+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PAUSED positionMs=0 volume=100 Aug 28 15:59:50 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:50.270+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:50 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:50.271+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PAUSED positionMs=0 volume=100 Aug 28 15:59:50 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:50.271+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:50 volumio volumio[1089]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:50 volumio volumio[1089]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:50 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status pause Aug 28 15:59:50 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status pause Aug 28 15:59:50 volumio sudo[23949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 15:59:50 volumio sudo[23949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:50 volumio sudo[23951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 15:59:50 volumio sudo[23951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:50 volumio sudo[23949]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:50 volumio volumio[1089]: info: peppyspectrum Daemon Stop Aug 28 15:59:50 volumio sudo[23951]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:50 volumio volumio[1089]: info: peppyspectrum Daemon Stop Aug 28 15:59:51 volumio vtcs[10619]: [2026-08-28 15:59:51.045] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:1 Aug 28 15:59:51 volumio vtcs[10619]: [2026-08-28 15:59:51.318] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Aug 28 15:59:51 volumio volumio[1089]: info: Pushing metadata Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.321+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PAUSED positionMs=0 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.321+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.325+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PLAYING positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.325+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.325+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PAUSED positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.325+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.325+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PAUSED positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.325+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- → Wakeup triggered Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status pause Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status pause Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status pause Aug 28 15:59:51 volumio sudo[23961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 15:59:51 volumio sudo[23961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status play Aug 28 15:59:51 volumio sudo[23963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 15:59:51 volumio sudo[23963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio sudo[23966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 15:59:51 volumio sudo[23966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 28 15:59:51 volumio sudo[23970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 15:59:51 volumio sudo[23970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio sudo[23961]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:51 volumio volumio[1089]: info: peppyspectrum Daemon Stop Aug 28 15:59:51 volumio sudo[23963]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:51 volumio volumio[1089]: info: peppyspectrum Daemon Stop Aug 28 15:59:51 volumio sudo[23966]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:51 volumio volumio[1089]: info: peppyspectrum Daemon Stop Aug 28 15:59:51 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Aug 28 15:59:51 volumio sudo[23970]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:51 volumio volumio[1089]: info: peppyspectrum Daemon Started Aug 28 15:59:51 volumio vtcs[10619]: [close:85] Entering Aug 28 15:59:51 volumio vtcs[10619]: [close:100] Exiting Aug 28 15:59:51 volumio volumio[1089]: info: Pushing metadata Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PLAYING positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PLAYING positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PAUSED positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PLAYING positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PLAYING positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.588+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- → Wakeup triggered Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- → Wakeup triggered Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- → Wakeup triggered Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- → Wakeup triggered Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status play Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status play Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status pause Aug 28 15:59:51 volumio sudo[23987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 15:59:51 volumio sudo[23987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status play Aug 28 15:59:51 volumio sudo[23992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 15:59:51 volumio sudo[23992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio sudo[23990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 15:59:51 volumio sudo[23990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status play Aug 28 15:59:51 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 28 15:59:51 volumio volumio[1089]: info: peppyspectrum Daemon Started Aug 28 15:59:51 volumio sudo[23987]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:51 volumio sudo[23996]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 15:59:51 volumio sudo[23996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio sudo[23990]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:51 volumio volumio[1089]: info: peppyspectrum Daemon Started Aug 28 15:59:51 volumio sudo[23998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 15:59:51 volumio sudo[23998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio sudo[23992]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Aug 28 15:59:51 volumio volumio[1089]: Job for peppyspectrum.service canceled. Aug 28 15:59:51 volumio vtcs[10619]: [close:85] Entering Aug 28 15:59:51 volumio vtcs[10619]: [close:100] Exiting Aug 28 15:59:51 volumio vtcs[10619]: [open:50] Opening with sample rate: 48000, type: 3 Aug 28 15:59:51 volumio vtcs[10619]: [start:106] Entering Aug 28 15:59:51 volumio vtcs[10619]: [start:113] Reopening PCM device... Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 28 15:59:51 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 28 15:59:51 volumio volumio[1089]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.798+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" state=STATUS_PLAYING positionMs=22761 volume=100 Aug 28 15:59:51 volumio volumio5-onboarding[1496]: time=2026-08-28T15:59:51.798+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:36404 @ 0xc000353710" id= title="Must Have Forgotten" Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- → Wakeup triggered Aug 28 15:59:51 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum status play Aug 28 15:59:51 volumio volumio[1089]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 28 15:59:51 volumio sudo[24012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 15:59:51 volumio sudo[24012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 15:59:51 volumio vtcs[10619]: [start:115] PCM opened successfully. Aug 28 15:59:51 volumio vtcs[10619]: [start:120] Exiting Aug 28 15:59:51 volumio vtcs[10619]: [feedThread:223] Entering Aug 28 15:59:51 volumio vtcs[10619]: [2026-08-28 15:59:51.984] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Aug 28 15:59:52 volumio vtcs[10619]: vtcs: pcm_meter.c:1220: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Aug 28 15:59:52 volumio vtcs[10618]: Aborted Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=134/n/a Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Consumed 3.721s CPU time. Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Aug 28 15:59:52 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Consumed 3.721s CPU time. Aug 28 15:59:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:52 volumio volumio[1089]: info: Executing endpoint tc_getconfig Aug 28 15:59:52 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 15:59:52 volumio vtcs[24021]: BYE TidalConnect Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Aug 28 15:59:52 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:52 volumio volumio[1089]: info: Executing endpoint tc_getconfig Aug 28 15:59:52 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 15:59:52 volumio vtcs[24024]: BYE TidalConnect Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 15:59:52 volumio systemd[1]: peppyspectrum.service: State 'final-sigterm' timed out. Killing. Aug 28 15:59:52 volumio systemd[1]: peppyspectrum.service: Killing process 23974 (python3) with signal SIGKILL. Aug 28 15:59:52 volumio systemd[1]: peppyspectrum.service: Killing process 24018 (python3) with signal SIGKILL. Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Aug 28 15:59:52 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:52 volumio systemd[1]: peppyspectrum.service: Failed with result 'timeout'. Aug 28 15:59:52 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. Aug 28 15:59:52 volumio systemd[1]: peppyspectrum.service: Failed with result 'timeout'. Aug 28 15:59:52 volumio systemd[1]: Failed to start peppyspectrum.service - peppyspectrum Daemon. Aug 28 15:59:52 volumio sudo[23996]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:52 volumio sudo[24012]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:52 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Aug 28 15:59:52 volumio volumio[1089]: Job for peppyspectrum.service failed because a timeout was exceeded. Aug 28 15:59:52 volumio volumio[1089]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. Aug 28 15:59:52 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Aug 28 15:59:52 volumio volumio[1089]: Job for peppyspectrum.service failed because a timeout was exceeded. Aug 28 15:59:52 volumio volumio[1089]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. Aug 28 15:59:52 volumio sudo[23998]: pam_unix(sudo:session): session closed for user root Aug 28 15:59:52 volumio volumio[1089]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Aug 28 15:59:52 volumio volumio[1089]: Job for peppyspectrum.service failed because a timeout was exceeded. Aug 28 15:59:52 volumio volumio[1089]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. Aug 28 15:59:52 volumio volumio[1089]: info: Executing endpoint tc_getconfig Aug 28 15:59:52 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 15:59:52 volumio vtcs[24027]: BYE TidalConnect Aug 28 15:59:52 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 15:59:53 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Aug 28 15:59:53 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:53 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:53 volumio volumio[1089]: info: Executing endpoint tc_getconfig Aug 28 15:59:53 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 15:59:53 volumio vtcs[24030]: BYE TidalConnect Aug 28 15:59:53 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 15:59:53 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. Aug 28 15:59:53 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:53 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:53 volumio volumio[1089]: info: Executing endpoint tc_getconfig Aug 28 15:59:53 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 15:59:53 volumio vtcs[24032]: BYE TidalConnect Aug 28 15:59:53 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 28 15:59:53 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 6. Aug 28 15:59:53 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 28 15:59:53 volumio systemd[1]: vtcs.service: Start request repeated too quickly. Aug 28 15:59:53 volumio systemd[1]: vtcs.service: Failed with result 'start-limit-hit'. Aug 28 15:59:53 volumio systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Aug 28 16:00:09 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:09.879+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s Aug 28 16:00:09 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:09.918+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=38.785109ms Aug 28 16:00:09 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:09.920+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=http://pushupdates.volumio.org duration=40.87164ms Aug 28 16:00:09 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:09.922+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=42.995147ms Aug 28 16:00:09 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:09.938+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=58.562973ms Aug 28 16:00:10 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:10.001+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://www.googleapis.com duration=121.773411ms Aug 28 16:00:10 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:10.045+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=165.656039ms Aug 28 16:00:10 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:10.056+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://securetoken.googleapis.com duration=176.385159ms Aug 28 16:00:10 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:10.071+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://database.volumio.cloud duration=191.53055ms Aug 28 16:00:10 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:10.081+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://functions.volumio.cloud duration=201.769965ms Aug 28 16:00:10 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:10.081+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://functions.volumio.cloud duration=202.140621ms Aug 28 16:00:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 16:00:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 16:00:10 volumio volumio[1089]: info: Discovery: Getting this device information Aug 28 16:00:10 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 28 16:00:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 16:00:10 volumio volumio[1089]: 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 16:00:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 16:00:10 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 16:00:10 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:10.613+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=https://google.com duration=734.067417ms Aug 28 16:00:11 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:11.139+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=http://cddb.volumio.org duration=1.259690877s Aug 28 16:00:11 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:11.309+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=94.410075ms timeout=10s endpoint=http://plugins.volumio.org duration=1.429721319s Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.495+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.528+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=32.753277ms Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.536+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=http://pushupdates.volumio.org duration=40.273652ms Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.537+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=41.15141ms Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.545+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=49.333663ms Aug 28 16:00:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 16:00:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 16:00:26 volumio volumio[1089]: info: Discovery: Getting this device information Aug 28 16:00:26 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 28 16:00:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.620+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://www.googleapis.com duration=124.692976ms Aug 28 16:00:26 volumio volumio[1089]: 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 16:00:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 16:00:26 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.653+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://securetoken.googleapis.com duration=157.517207ms Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.663+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=167.636971ms Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.686+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://database.volumio.cloud duration=190.19975ms Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.689+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://functions.volumio.cloud duration=193.90077ms Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.692+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://functions.volumio.cloud duration=196.314697ms Aug 28 16:00:26 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:26.952+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=http://cddb.volumio.org duration=456.069658ms Aug 28 16:00:27 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:27.013+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=https://google.com duration=517.658674ms Aug 28 16:00:27 volumio volumio5-onboarding[1496]: time=2026-08-28T16:00:27.117+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:36404 @ 0xc000353710" latency=87.820725ms timeout=10s endpoint=http://plugins.volumio.org duration=621.260676ms Aug 28 16:00:32 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 16:00:32 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 28 16:00:42 volumio volumio[1089]: info: Stopping peppyspectrum service Aug 28 16:00:42 volumio volumio[1089]: info: CoreStateMachine::stop Aug 28 16:00:42 volumio volumio[1089]: info: CoreStateMachine::serviceStop Aug 28 16:00:42 volumio volumio[1089]: info: CoreCommandRouter::serviceStop Aug 28 16:00:42 volumio volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 16:00:42 volumio volumio[1089]: TypeError: Cannot read properties of undefined (reading 'then') Aug 28 16:00:42 volumio volumio[1089]: at peppyspectrum.onStop (/data/plugins/user_interface/peppyspectrum/index.js:60:43) Aug 28 16:00:42 volumio volumio[1089]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 28 16:00:42 volumio volumio[1089]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Aug 28 16:00:42 volumio volumio[1089]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Aug 28 16:00:42 volumio volumio[1089]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Aug 28 16:00:42 volumio volumio[1089]: at Socket.emit (node:events:514:28) Aug 28 16:00:42 volumio volumio[1089]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 28 16:00:42 volumio volumio[1089]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 28 16:00:42 volumio volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 16:00:42 volumio sudo[24174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 15:59' Aug 28 16:00:42 volumio sudo[24174]: 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"