Oct 11 22:59:01 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:01 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioNext Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::next Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::stop Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::stPlaybackTimer Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::updateTrackBlock Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrackBlock Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 1 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::serviceStop Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 1 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::serviceStop Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::stop Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.605+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.606+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand stop took 10 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::play index undefined Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::startPlaybackTimer Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Billy Joel - 52nd Street/03. My Life.flac Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::updateTrackBlock Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrackBlock Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 4 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand stop took 4 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 3 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 2 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Honesty","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/02. Honesty.flac","trackType":"flac"} Oct 11 22:59:02 volumioboss volumio[1248]: verbose: CURRENT POSITION 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService stop Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus stop Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: No code Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.629+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.629+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.630+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.631+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.631+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.631+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 23ms Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 22:59:02 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand clear took 19 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 11 22:59:02 volumioboss volumio[1248]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 24ms Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 24ms Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 22:59:02 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 22:59:02 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 22:59:02 volumioboss volumio[1248]: error: updateQueue error: null Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 22:59:02 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 11ms Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand add "INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" took 11 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 9ms Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 9ms Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand play Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 22:59:02 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 22:59:02 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 15ms Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand play took 14 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 13ms Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 13ms Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 23 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 19 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 20 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 1 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Life","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/03. My Life.flac","trackType":"flac"} Oct 11 22:59:02 volumioboss volumio[1248]: verbose: CURRENT POSITION 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus stop Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 26ms Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: info: Oct 11 22:59:02 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 2 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand status took 1 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":284,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"655 Kbps","isStreaming":false,"title":"My Life","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/03. My Life.flac","trackType":"flac"} Oct 11 22:59:02 volumioboss volumio[1248]: verbose: CURRENT POSITION 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 22:59:02 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":377,"duration":284,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"655 Kbps","isStreaming":false,"title":"My Life","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/03. My Life.flac","trackType":"flac"} Oct 11 22:59:02 volumioboss volumio[1248]: verbose: CURRENT POSITION 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 22:59:02 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":284,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"My Life","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/03. My Life.flac","trackType":"flac"} Oct 11 22:59:02 volumioboss volumio[1248]: verbose: CURRENT POSITION 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 22:59:02 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.709+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=343 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.709+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=343 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.710+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/02. Honesty.flac" title=Honesty Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.711+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/02. Honesty.flac" title=Honesty Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.711+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=343 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.712+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=343 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.712+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=377 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.712+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=377 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.713+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=377 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.714+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=377 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.714+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.714+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.715+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.716+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 60ms Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 59ms Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 41ms Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":284,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"My Life","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/03. My Life.flac","trackType":"flac"} Oct 11 22:59:02 volumioboss volumio[1248]: verbose: CURRENT POSITION 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 22:59:02 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":284,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"817 Kbps","isStreaming":false,"title":"My Life","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/03. My Life.flac","trackType":"flac"} Oct 11 22:59:02 volumioboss volumio[1248]: verbose: CURRENT POSITION 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 22:59:02 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 22:59:02 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 22:59:02 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.740+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.740+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.741+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.741+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.742+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.742+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.743+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.744+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 62ms Oct 11 22:59:02 volumioboss volumio[1248]: info: ------------------------------ 63ms Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.830+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.830+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.950+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:02 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:02.950+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.069+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.070+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.190+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.190+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.429+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.430+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.550+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.550+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.670+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.670+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.791+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.791+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.912+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:03 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:03.912+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:04 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:04.030+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:04 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:04.030+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:04 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:04.149+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:04 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:04.150+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:04 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:04.269+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:04 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:04.270+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:04 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:04.390+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:04 volumioboss volumio5-onboarding[1536]: time=2026-10-11T22:59:04.390+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 22:59:06 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:06 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:11 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:11 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:16 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:16 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:21 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:21 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:26 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:26 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:31 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:31 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:36 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:36 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:41 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:41 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:46 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:46 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:51 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:51 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 22:59:56 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 22:59:56 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:01 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:01 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:06 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:06 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:11 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:11 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.436+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.563+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=http://pushupdates.volumio.org duration=124.983705ms Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.596+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=160.431314ms Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.695+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=257.069531ms Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.717+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://www.googleapis.com duration=278.797521ms Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.729+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://securetoken.googleapis.com duration=289.444805ms Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.737+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=299.358117ms Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.796+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://functions.volumio.cloud duration=358.229382ms Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.800+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://functions.volumio.cloud duration=362.336705ms Oct 11 23:00:14 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:14.855+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=418.281577ms Oct 11 23:00:15 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:15.075+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=http://cddb.volumio.org duration=638.052983ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 11 23:00:15 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:15.102+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=http://plugins.volumio.org duration=663.784168ms Oct 11 23:00:15 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:15.406+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://google.com duration=970.011261ms Oct 11 23:00:15 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:15.480+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" latency=57.245854ms timeout=10s endpoint=https://database.volumio.cloud duration=1.041516694s Oct 11 23:00:15 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 23:00:15 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 23:00:15 volumioboss volumio[1248]: info: Discovery: Getting this device information Oct 11 23:00:15 volumioboss volumio[1248]: info: CoreCommandRouter::volumioGetState Oct 11 23:00:15 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 23:00:15 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 23:00:16 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:16 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:16 volumioboss volumio[1248]: verbose: New Socket.io Connection to 192.168.233.19:3000 from 192.168.233.32 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 11 23:00:16 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 23:00:16 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 23:00:21 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:21 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:26 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:26 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:31 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:31 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioNext Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::next Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::stop Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::stPlaybackTimer Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::updateTrackBlock Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrackBlock Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::serviceStop Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 2 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::serviceStop Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::stop Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.748+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.749+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand stop took 13 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::play index undefined Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::startPlaybackTimer Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::updateTrackBlock Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrackBlock Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 3 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand stop took 3 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 23:00:31 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 3 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 2 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand clear took 2 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Life","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/03. My Life.flac","trackType":"flac"} Oct 11 23:00:31 volumioboss volumio[1248]: verbose: CURRENT POSITION 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService stop Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus stop Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: No code Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.775+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.775+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.777+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.777+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.778+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.778+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 25ms Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 23:00:31 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 23:00:31 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 23:00:31 volumioboss volumio[1248]: error: updateQueue error: null Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 23:00:31 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 22ms Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand add "INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" took 22 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 3ms Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 2ms Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand play Oct 11 23:00:31 volumioboss volumio[1248]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 11 23:00:31 volumioboss volumio[1248]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 28ms Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 28ms Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 23:00:31 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces system playlist update Oct 11 23:00:31 volumioboss volumio[1248]: info: Ignoring MPD Status Update Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 12ms Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand play took 10 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 9ms Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 9ms Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 25 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 26 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 25 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 2 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 2 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Zanzibar","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac","trackType":"flac"} Oct 11 23:00:31 volumioboss volumio[1248]: verbose: CURRENT POSITION 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus stop Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 30ms Oct 11 23:00:31 volumioboss volumio[1248]: info: Oct 11 23:00:31 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand status took 1 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseState Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":320,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"651 Kbps","isStreaming":false,"title":"Zanzibar","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac","trackType":"flac"} Oct 11 23:00:31 volumioboss volumio[1248]: verbose: CURRENT POSITION 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:00:31 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"586 Kbps","isStreaming":false,"title":"Zanzibar","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac","trackType":"flac"} Oct 11 23:00:31 volumioboss volumio[1248]: verbose: CURRENT POSITION 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:00:31 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"586 Kbps","isStreaming":false,"title":"Zanzibar","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac","trackType":"flac"} Oct 11 23:00:31 volumioboss volumio[1248]: verbose: CURRENT POSITION 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:00:31 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"586 Kbps","isStreaming":false,"title":"Zanzibar","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac","trackType":"flac"} Oct 11 23:00:31 volumioboss volumio[1248]: verbose: CURRENT POSITION 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:00:31 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.861+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=320 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.862+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=320 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.862+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=320 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.863+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=320 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.864+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.864+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.864+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.865+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.866+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.866+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.867+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.867+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.868+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.868+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.869+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.870+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.870+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.870+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/03. My Life.flac" title="My Life" Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 72ms Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 72ms Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 48ms Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 47ms Oct 11 23:00:31 volumioboss volumio[1248]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 11 23:00:31 volumioboss volumio[1248]: verbose: ControllerMpd::parseTrackInfo Oct 11 23:00:31 volumioboss volumio[1248]: info: ControllerMpd::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::servicePushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":314,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"586 Kbps","isStreaming":false,"title":"Zanzibar","artist":"Billy Joel","album":"52nd Street (2010 HDCD)","uri":"INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac","trackType":"flac"} Oct 11 23:00:31 volumioboss volumio[1248]: verbose: CURRENT POSITION 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState stateService play Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::syncState currentStatus play Oct 11 23:00:31 volumioboss volumio[1248]: info: Received an update from plugin. extracting info from payload Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:31 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:31 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.893+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.893+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.894+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.895+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_PLAYING positionMs=881 volume=100 Oct 11 23:00:31 volumioboss volumio[1248]: info: ------------------------------ 56ms Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.990+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:31 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:31.990+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.170+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.170+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.320+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.320+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.439+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.440+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.560+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.560+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.770+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.770+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.830+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.831+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.949+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:32 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:32.950+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.070+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.070+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.189+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.190+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.309+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.310+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.579+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.580+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.699+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:33 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:33.700+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:36 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:36 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:41 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:41 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:46 volumioboss dbus-daemon[692]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Oct 11 23:00:46 volumioboss volumio[1248]: info: Discovery: Restarting Browsing Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreStateMachine::ClearQueue Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreStateMachine::stop Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreStateMachine::stPlaybackTimer Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreStateMachine::updateTrackBlock Oct 11 23:00:50 volumioboss volumio[1248]: info: CorePlayQueue::getTrackBlock Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreStateMachine::pushState Oct 11 23:00:50 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushState Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreStateMachine::serviceStop Oct 11 23:00:50 volumioboss volumio[1248]: info: CorePlayQueue::getTrack 3 Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreCommandRouter::serviceStop Oct 11 23:00:50 volumioboss volumio[1248]: info: ControllerMpd::stop Oct 11 23:00:50 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 23:00:50 volumioboss volumio[1248]: info: CorePlayQueue::clearPlayQueue Oct 11 23:00:50 volumioboss volumio[1248]: info: CorePlayQueue::saveQueue Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreCommandRouter::volumioPushQueue Oct 11 23:00:50 volumioboss volumio[1248]: info: CoreStateMachine::addQueueItems Oct 11 23:00:50 volumioboss volumio[1248]: info: CorePlayQueue::addQueueItems Oct 11 23:00:50 volumioboss volumio[1248]: info: Preload queue cleared Oct 11 23:00:50 volumioboss volumio[1248]: info: Adding Item to queue: music-library/INTERNAL/Cassandra Wilson - After The Beginning Again Oct 11 23:00:50 volumioboss volumio[1248]: info: Exploding uri music-library/INTERNAL/Cassandra Wilson - After The Beginning Again in service mpd Oct 11 23:00:50 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:50.973+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:50 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:50.974+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 11 23:00:50 volumioboss volumio[1248]: info: Oct 11 23:00:50 volumioboss volumio[1248]: ---------------------------- MPD announces state update: player Oct 11 23:00:50 volumioboss volumio[1248]: info: sendMpdCommand stop took 23 milliseconds Oct 11 23:00:50 volumioboss volumio[1248]: info: ControllerMpd::getState Oct 11 23:00:50 volumioboss volumio[1248]: verbose: ControllerMpd::sendMpdCommand status Oct 11 23:00:50 volumioboss volumio[1248]: info: ALBUMART /albumart?cacheid=56&web=Cassandra%20Wilson/After%20The%20Beginning%20Again/extralarge&path=%2Fmnt%2FINTERNAL%2FCassandra%20Wilson%20-%20After%20The%20Beginning%20Again%2FCassandra%20Wilson%20-%20After%20The%20Beginning%20Again.cue&metadata=false Oct 11 23:00:50 volumioboss volumio[1248]: info: URI /mnt/INTERNAL/Cassandra Wilson - After The Beginning Again/Cassandra Wilson - After The Beginning Again.cue Oct 11 23:00:50 volumioboss volumio[1248]: info: ALBUMART /albumart?cacheid=56&web=Cassandra%20Wilson/After%20The%20Beginning%20Again/extralarge&path=%2Fmnt%2FINTERNAL%2FCassandra%20Wilson%20-%20After%20The%20Beginning%20Again%2FCassandra%20Wilson%20-%20After%20The%20Beginning%20Again.cue&metadata=false Oct 11 23:00:50 volumioboss volumio[1248]: info: URI /mnt/INTERNAL/Cassandra Wilson - After The Beginning Again/Cassandra Wilson - After The Beginning Again.cue Oct 11 23:00:50 volumioboss volumio[1248]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:00:50 volumioboss volumio[1248]: Error: Unable to resolve or reject the same promise twice Oct 11 23:00:50 volumioboss volumio[1248]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 11 23:00:50 volumioboss volumio[1248]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 11 23:00:50 volumioboss volumio[1248]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 11 23:00:50 volumioboss volumio[1248]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 11 23:00:50 volumioboss volumio[1248]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 11 23:00:50 volumioboss volumio[1248]: at Socket.emit (node:events:514:28) Oct 11 23:00:50 volumioboss volumio[1248]: at addChunk (node:internal/streams/readable:343:12) Oct 11 23:00:50 volumioboss volumio[1248]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 11 23:00:50 volumioboss volumio[1248]: at Readable.push (node:internal/streams/readable:253:10) Oct 11 23:00:50 volumioboss volumio[1248]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 11 23:00:50 volumioboss volumio[1248]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 23:00:51 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:51.070+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.233.32:33224,00:00:00:00:00:00%02 @ 0x1b820c0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:51 volumioboss volumio5-onboarding[1536]: time=2026-10-11T23:00:51.070+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1b82bd0" id="mnt/INTERNAL/Billy Joel - 52nd Street/04. Zanzibar.flac" title=Zanzibar Oct 11 23:00:51 volumioboss sudo[16535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 22:59' Oct 11 23:00:51 volumioboss sudo[16535]: 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"