Aug 27 10:30:00 volumio sudo[6691]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 10:30:00 volumio sudo[6691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:30:00 volumio sudo[6691]: pam_unix(sudo:session): session closed for user root Aug 27 10:30:00 volumio sudo[6693]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 10:30:00 volumio sudo[6693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 10:30:00 volumio sudo[6693]: pam_unix(sudo:session): session closed for user root Aug 27 10:30:00 volumio volumio[1365]: verbose: New Socket.io Connection to 10.0.0.235 from 10.0.0.206 UA: Mozilla/5.0 (Linux; Android 16; SM-S928U Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 27 10:30:00 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Aug 27 10:30:00 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Aug 27 10:30:00 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 10:30:00 volumio volumio[1365]: info: Listing playlists Aug 27 10:30:00 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 10:30:00 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 10:30:00 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 10:30:00 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 10:30:02 volumio volumio[1365]: info: CoreCommandRouter::volumioPlay Aug 27 10:30:02 volumio volumio[1365]: info: CoreStateMachine::play index undefined Aug 27 10:30:02 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 10:30:02 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Aug 27 10:30:02 volumio volumio[1365]: info: CoreStateMachine::startPlaybackTimer Aug 27 10:30:02 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Aug 27 10:30:02 volumio volumio[1365]: info: [1787844602522] ControllerWebradio::clearAddPlayTrack Aug 27 10:30:02 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 10:30:02 volumio volumio[1365]: info: sendMpdCommand stop took 63 milliseconds Aug 27 10:30:02 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 10:30:02 volumio volumio[1365]: info: sendMpdCommand clear took 15 milliseconds Aug 27 10:30:02 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand load "https://radiok.broadcasttool.stream/play_256" Aug 27 10:30:02 volumio volumio[1365]: info: Aug 27 10:30:02 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Aug 27 10:30:02 volumio volumio[1365]: info: Ignoring MPD Status Update Aug 27 10:30:03 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:03.325-05:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Aug 27 10:30:03 volumio dbus-daemon[750]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.16" (uid=0 pid=1591 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.6" (uid=0 pid=1042 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 27 10:30:03 volumio volumio[1365]: error: updateQueue error: null Aug 27 10:30:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand add "https://radiok.broadcasttool.stream/play_256" Aug 27 10:30:03 volumio volumio[1365]: info: ------------------------------ 1307ms Aug 27 10:30:03 volumio volumio[1365]: info: Aug 27 10:30:03 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Aug 27 10:30:03 volumio volumio[1365]: info: Ignoring MPD Status Update Aug 27 10:30:03 volumio volumio[1365]: info: sendMpdCommand add "https://radiok.broadcasttool.stream/play_256" took 3 milliseconds Aug 27 10:30:03 volumio volumio[1365]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 10:30:03 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand play Aug 27 10:30:03 volumio volumio[1365]: info: ------------------------------ 7ms Aug 27 10:30:03 volumio volumio[1365]: info: sendMpdCommand play took 6 milliseconds Aug 27 10:30:04 volumio volumio[1365]: info: Aug 27 10:30:04 volumio volumio[1365]: ---------------------------- MPD announces state update: player Aug 27 10:30:04 volumio volumio[1365]: info: ControllerMpd::getState Aug 27 10:30:04 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Aug 27 10:30:06 volumio volumio[1365]: info: sendMpdCommand status took 1691 milliseconds Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 10:30:06 volumio volumio[1365]: info: Aug 27 10:30:06 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Aug 27 10:30:06 volumio volumio[1365]: info: Ignoring MPD Status Update Aug 27 10:30:06 volumio volumio[1365]: info: Aug 27 10:30:06 volumio volumio[1365]: ---------------------------- MPD announces state update: player Aug 27 10:30:06 volumio volumio[1365]: info: Aug 27 10:30:06 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Aug 27 10:30:06 volumio volumio[1365]: info: Ignoring MPD Status Update Aug 27 10:30:06 volumio volumio[1365]: info: Aug 27 10:30:06 volumio volumio[1365]: ---------------------------- MPD announces state update: player Aug 27 10:30:06 volumio volumio[1365]: info: ControllerMpd::getState Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Aug 27 10:30:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 27 10:30:06 volumio volumio[1365]: info: ControllerMpd::getState Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Aug 27 10:30:06 volumio volumio[1365]: info: ControllerMpd::pushState Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Aug 27 10:30:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Aug 27 10:30:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"\"Sometimes\" by my bloody valentine from \"Loveless\"","artist":"Radio K - Real College Radio (KUOM)","album":null,"uri":"https://radiok.broadcasttool.stream/play_256","trackType":""} Aug 27 10:30:06 volumio volumio[1365]: verbose: CURRENT POSITION 0 Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus stop Aug 27 10:30:06 volumio volumio[1365]: info: ------------------------------ 1736ms Aug 27 10:30:06 volumio volumio[1365]: info: ------------------------------ 45ms Aug 27 10:30:06 volumio volumio[1365]: info: sendMpdCommand status took 41 milliseconds Aug 27 10:30:06 volumio volumio[1365]: info: ------------------------------ 45ms Aug 27 10:30:06 volumio volumio[1365]: info: sendMpdCommand status took 42 milliseconds Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::parseState Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 10:30:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 32 milliseconds Aug 27 10:30:06 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 31 milliseconds Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Aug 27 10:30:06 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Aug 27 10:30:06 volumio volumio[1365]: info: ControllerMpd::pushState Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Aug 27 10:30:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Aug 27 10:30:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"\"Sometimes\" by my bloody valentine from \"Loveless\"","artist":"Radio K - Real College Radio (KUOM)","album":null,"uri":"https://radiok.broadcasttool.stream/play_256","trackType":""} Aug 27 10:30:06 volumio volumio[1365]: verbose: CURRENT POSITION 0 Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Aug 27 10:30:06 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::pushState Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Aug 27 10:30:06 volumio volumio[1365]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::pushState Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Aug 27 10:30:06 volumio volumio[1365]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 27 10:30:06 volumio volumio[1365]: info: ControllerMpd::pushState Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Aug 27 10:30:06 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Aug 27 10:30:06 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"\"Sometimes\" by my bloody valentine from \"Loveless\"","artist":"Radio K - Real College Radio (KUOM)","album":null,"uri":"https://radiok.broadcasttool.stream/play_256","trackType":""} Aug 27 10:30:06 volumio volumio[1365]: verbose: CURRENT POSITION 0 Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Aug 27 10:30:06 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::pushState Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Aug 27 10:30:06 volumio volumio[1365]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 27 10:30:06 volumio volumio[1365]: info: CoreStateMachine::pushState Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Aug 27 10:30:06 volumio volumio[1365]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 27 10:30:06 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:06.453-05:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2000ab0" state=STATUS_PLAYING positionMs=293 volume=50 Aug 27 10:30:06 volumio volumio[1365]: info: ------------------------------ 194ms Aug 27 10:30:06 volumio volumio[1365]: info: ------------------------------ 194ms Aug 27 10:30:06 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:06.458-05:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2000ab0" state=STATUS_PLAYING positionMs=293 volume=50 Aug 27 10:30:06 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:06.458-05:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2000ab0" state=STATUS_PLAYING positionMs=293 volume=50 Aug 27 10:30:06 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:06.458-05:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01 @ 0x2000ab0" state=STATUS_PLAYING positionMs=293 volume=50 Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Aug 27 10:30:06 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Aug 27 10:30:09 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:09.817-05:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 27 10:30:10 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:09.817-05:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2000ab0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Aug 27 10:30:10 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:10.955-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.206:38978 @ 0x219a510" state=STATUS_PLAYING positionMs=293 volume=50 Aug 27 10:30:10 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:10.955-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.206:38978,00:00:00:00:00:00%02 @ 0x219a510" id=https://radiok.broadcasttool.stream/play_256 title="\"Sometimes\" by my bloody valentine from \"Loveless\"" Aug 27 10:30:10 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:10.956-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x20bc8a0" id=https://radiok.broadcasttool.stream/play_256 title="\"Sometimes\" by my bloody valentine from \"Loveless\"" Aug 27 10:30:10 volumio volumio[1365]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 27 10:30:13 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:13.469-05:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 27 10:30:13 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:13.469-05:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2000ab0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Aug 27 10:30:13 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:13.470-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.206:38978 @ 0x219a510" state=STATUS_PLAYING positionMs=293 volume=50 Aug 27 10:30:13 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:13.470-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.206:38978,00:00:00:00:00:00%02 @ 0x219a510" id=https://radiok.broadcasttool.stream/play_256 title="\"Sometimes\" by my bloody valentine from \"Loveless\"" Aug 27 10:30:13 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:13.471-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x20bc8a0" id=https://radiok.broadcasttool.stream/play_256 title="\"Sometimes\" by my bloody valentine from \"Loveless\"" Aug 27 10:30:16 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:16.948-05:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 27 10:30:16 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:16.949-05:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2000ab0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Aug 27 10:30:16 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:16.949-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.206:38978 @ 0x219a510" state=STATUS_PLAYING positionMs=293 volume=50 Aug 27 10:30:16 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:16.949-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.206:38978,00:00:00:00:00:00%02 @ 0x219a510" id=https://radiok.broadcasttool.stream/play_256 title="\"Sometimes\" by my bloody valentine from \"Loveless\"" Aug 27 10:30:16 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:16.950-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x20bc8a0" id=https://radiok.broadcasttool.stream/play_256 title="\"Sometimes\" by my bloody valentine from \"Loveless\"" Aug 27 10:30:19 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 10:30:19 volumio volumio[1365]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 27 10:30:19 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 27 10:30:19 volumio volumio[1365]: info: Received Get System Version Aug 27 10:30:19 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 10:30:19 volumio volumio[1365]: info: Received Get System Info Aug 27 10:30:19 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:30:19 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:30:19 volumio volumio[1365]: info: Discovery: Getting this device information Aug 27 10:30:19 volumio volumio[1365]: info: CoreCommandRouter::volumioGetState Aug 27 10:30:19 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:30:20 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:20.522-05:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=1 chunks=1 index=0 tries=11 Aug 27 10:30:20 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:20.522-05:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%01 @ 0x2000ab0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Aug 27 10:30:20 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:20.522-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.206:38978 @ 0x219a510" state=STATUS_PLAYING positionMs=293 volume=50 Aug 27 10:30:20 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:20.524-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.206:38978,00:00:00:00:00:00%02 @ 0x219a510" id=https://radiok.broadcasttool.stream/play_256 title="\"Sometimes\" by my bloody valentine from \"Loveless\"" Aug 27 10:30:20 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:20.524-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x20bc8a0" id=https://radiok.broadcasttool.stream/play_256 title="\"Sometimes\" by my bloody valentine from \"Loveless\"" Aug 27 10:30:24 volumio volumio[1365]: info: Aug 27 10:30:24 volumio volumio[1365]: ---------------------------- MPD announces system playlist update Aug 27 10:30:24 volumio volumio[1365]: info: Ignoring MPD Status Update Aug 27 10:30:24 volumio volumio[1365]: info: Aug 27 10:30:24 volumio volumio[1365]: ---------------------------- MPD announces state update: player Aug 27 10:30:24 volumio volumio[1365]: info: ControllerMpd::getState Aug 27 10:30:24 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand status Aug 27 10:30:24 volumio volumio[1365]: info: ------------------------------ 9ms Aug 27 10:30:24 volumio volumio[1365]: info: sendMpdCommand status took 6 milliseconds Aug 27 10:30:24 volumio volumio[1365]: verbose: ControllerMpd::parseState Aug 27 10:30:24 volumio volumio[1365]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 10:30:24 volumio volumio[1365]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 10:30:24 volumio volumio[1365]: verbose: ControllerMpd::parseTrackInfo Aug 27 10:30:24 volumio volumio[1365]: info: ControllerMpd::pushState Aug 27 10:30:24 volumio volumio[1365]: info: CoreCommandRouter::servicePushState Aug 27 10:30:24 volumio volumio[1365]: info: CorePlayQueue::getTrack 0 Aug 27 10:30:24 volumio volumio[1365]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18856,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"\"Summertime\" by The Sundays from \"Static & Silence\"","artist":"Radio K - Real College Radio (KUOM)","album":null,"uri":"https://radiok.broadcasttool.stream/play_256","trackType":""} Aug 27 10:30:24 volumio volumio[1365]: verbose: CURRENT POSITION 0 Aug 27 10:30:24 volumio volumio[1365]: info: CoreStateMachine::syncState stateService play Aug 27 10:30:24 volumio volumio[1365]: info: CoreStateMachine::syncState currentStatus play Aug 27 10:30:24 volumio volumio[1365]: info: Received an update from plugin. extracting info from payload Aug 27 10:30:24 volumio volumio[1365]: info: CoreStateMachine::pushState Aug 27 10:30:24 volumio volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 10:30:24 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Aug 27 10:30:24 volumio volumio[1365]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 27 10:30:24 volumio volumio[1365]: info: CoreStateMachine::pushState Aug 27 10:30:24 volumio volumio[1365]: info: CoreCommandRouter::volumioPushState Aug 27 10:30:24 volumio volumio[1365]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 27 10:30:24 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:24.052-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.206:38978,00:00:00:00:00:00%02 @ 0x219a510" state=STATUS_PLAYING positionMs=17942 volume=50 Aug 27 10:30:24 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:24.054-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.206:38978,00:00:00:00:00:00%02 @ 0x219a510" state=STATUS_PLAYING positionMs=17942 volume=50 Aug 27 10:30:24 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:24.055-05:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x20bc8a0" state=STATUS_PLAYING positionMs=17942 volume=50 Aug 27 10:30:24 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:24.055-05:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x20bc8a0" state=STATUS_PLAYING positionMs=17942 volume=50 Aug 27 10:30:24 volumio volumio[1365]: info: ------------------------------ 41ms Aug 27 10:30:24 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:24.233-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.206:38978,00:00:00:00:00:00%02 @ 0x219a510" id=https://radiok.broadcasttool.stream/play_256 title="\"Summertime\" by The Sundays from \"Static & Silence\"" Aug 27 10:30:24 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:24.233-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x20bc8a0" id=https://radiok.broadcasttool.stream/play_256 title="\"Summertime\" by The Sundays from \"Static & Silence\"" Aug 27 10:30:24 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:24.293-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.206:38978,00:00:00:00:00:00%02 @ 0x219a510" id=https://radiok.broadcasttool.stream/play_256 title="\"Summertime\" by The Sundays from \"Static & Silence\"" Aug 27 10:30:24 volumio volumio5-onboarding[1591]: time=2026-08-27T10:30:24.295-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x20bc8a0" id=https://radiok.broadcasttool.stream/play_256 title="\"Summertime\" by The Sundays from \"Static & Silence\"" Aug 27 10:31:43 volumio volumio[1365]: info: [ytmusic] Refresh session PO token Aug 27 10:31:43 volumio volumio[1365]: info: [ytmusic] Obtained session PO token using datasyncIdToken (expires in 43104 seconds) Aug 27 10:31:43 volumio volumio[1365]: info: [ytmusic] Going to refresh session PO token in 43004 seconds Aug 27 10:31:43 volumio volumio[1365]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 10:31:43 volumio volumio[1365]: TypeError: Chaining cycle detected for promise # Aug 27 10:31:43 volumio volumio[1365]: at #doGetSessionPoToken (/data/plugins/music_service/ytmusic/node_modules/volumio-yt-support/dist/lib/innertube/Wrapper.js:128:21) Aug 27 10:31:43 volumio volumio[1365]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 27 10:31:43 volumio volumio[1365]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 10:31:46 volumio sudo[7068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 10:30' Aug 27 10:31:46 volumio sudo[7068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"