-- Logs begin at Fri 2026-09-18 05:29:52 EDT, end at Thu 2026-10-15 19:30:11 EDT. -- Oct 15 19:29:01 mano-volumio volumio[1328]: verbose: New Socket.io Connection to 192.168.86.43 from 192.168.86.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 15 19:29:01 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:01 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 19:29:01 mano-volumio volumio[1328]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 15 19:29:01 mano-volumio volumio[1328]: info: Listing playlists Oct 15 19:29:04 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 19:29:04 mano-volumio volumio[1328]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 15 19:29:04 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 15 19:29:04 mano-volumio volumio[1328]: info: Received Get System Version Oct 15 19:29:04 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 19:29:04 mano-volumio volumio[1328]: info: Received Get System Info Oct 15 19:29:04 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 19:29:04 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 19:29:04 mano-volumio volumio[1328]: info: Discovery: Getting this device information Oct 15 19:29:04 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:04 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 19:29:10 mano-volumio volumiologrotate[762]: ls: cannot access '/var/log/samba/log.wb-MANO': No such file or directory Oct 15 19:29:10 mano-volumio volumiologrotate[762]: ls: cannot access 'VOLUMIO': No such file or directory Oct 15 19:29:15 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioRandom Oct 15 19:29:15 mano-volumio volumio[1328]: info: CoreCommandRouter::writePlayerControls Oct 15 19:29:15 mano-volumio volumio[1328]: info: CoreStateMachine::setRepeat true single false Oct 15 19:29:15 mano-volumio volumio[1328]: info: WARNING: No repeat method for plugin bluetooth Oct 15 19:29:24 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetQueue Oct 15 19:29:24 mano-volumio volumio[1328]: info: CoreStateMachine::getQueue Oct 15 19:29:24 mano-volumio volumio[1328]: info: CorePlayQueue::getQueue Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPlay Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: UNSET VOLATILE: Service: bluetooth Oct 15 19:29:26 mano-volumio volumio[1328]: info: Bluetooth Detach Command Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::play index 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::stop Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::stPlaybackTimer Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::updateTrackBlock Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrackBlock Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:26 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:26 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::serviceStop Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::serviceStop Oct 15 19:29:26 mano-volumio volumio[1328]: info: [1792106966927] ControllerWebradio::stop Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 19:29:26 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:26.928-04:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%09 @ 0x2380030" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 19:29:26 mano-volumio volumio[1328]: info: Oct 15 19:29:26 mano-volumio volumio[1328]: ---------------------------- MPD announces state update: player Oct 15 19:29:26 mano-volumio volumio[1328]: info: sendMpdCommand stop took 4 milliseconds Oct 15 19:29:26 mano-volumio volumio[1328]: info: ControllerMpd::getState Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand status Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::play index undefined Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::startPlaybackTimer Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 15 19:29:26 mano-volumio volumio[1328]: info: [1792106966932] ControllerWebradio::clearAddPlayTrack Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 19:29:26 mano-volumio volumio[1328]: info: sendMpdCommand status took 1 milliseconds Oct 15 19:29:26 mano-volumio volumio[1328]: info: sendMpdCommand stop took 0 milliseconds Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: ControllerMpd::parseState Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 19:29:26 mano-volumio volumio[1328]: info: Oct 15 19:29:26 mano-volumio volumio[1328]: ---------------------------- MPD announces system playlist update Oct 15 19:29:26 mano-volumio volumio[1328]: info: Ignoring MPD Status Update Oct 15 19:29:26 mano-volumio volumio[1328]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 15 19:29:26 mano-volumio volumio[1328]: info: sendMpdCommand clear took 11 milliseconds Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: ControllerMpd::parseTrackInfo Oct 15 19:29:26 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand load "http://streaming.radio.co:80/s297e618a7/listen" Oct 15 19:29:26 mano-volumio volumio[1328]: info: ControllerMpd::pushState Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::servicePushState Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:26 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:26 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:26 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:26 mano-volumio volumio[1328]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 15 19:29:26 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:26.947-04:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%09 @ 0x2380030" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 19:29:26 mano-volumio volumio[1328]: info: ------------------------------ 16ms Oct 15 19:29:26 mano-volumio volumio[1328]: error: updateQueue error: null Oct 15 19:29:26 mano-volumio volumio[1328]: info: ------------------------------ 5ms Oct 15 19:29:26 mano-volumio pulseaudio[1774]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Oct 15 19:29:26 mano-volumio volumio[1328]: info: Bluetooth Audio successfully Disabled Oct 15 19:29:26 mano-volumio volumio[1328]: info: Bluetooth Successfully Detached Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand add "http://streaming.radio.co:80/s297e618a7/listen" Oct 15 19:29:27 mano-volumio volumio[1328]: info: Oct 15 19:29:27 mano-volumio volumio[1328]: ---------------------------- MPD announces system playlist update Oct 15 19:29:27 mano-volumio volumio[1328]: info: Ignoring MPD Status Update Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand add "http://streaming.radio.co:80/s297e618a7/listen" took 0 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand play Oct 15 19:29:27 mano-volumio volumio[1328]: info: ------------------------------ 1ms Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand play took 1 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: info: Oct 15 19:29:27 mano-volumio volumio[1328]: ---------------------------- MPD announces state update: player Oct 15 19:29:27 mano-volumio volumio[1328]: info: ControllerMpd::getState Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand status Oct 15 19:29:27 mano-volumio volumio[1328]: info: Oct 15 19:29:27 mano-volumio volumio[1328]: ---------------------------- MPD announces state update: player Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand status took 2 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: info: ControllerMpd::getState Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand status Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::parseState Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand status took 0 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::parseState Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::parseTrackInfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: ControllerMpd::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::servicePushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"listen","artist":null,"album":null,"uri":"http://streaming.radio.co:80/s297e618a7/listen","trackType":""} Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: CURRENT POSITION 0 Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::syncState stateService play Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::syncState currentStatus stop Oct 15 19:29:27 mano-volumio volumio[1328]: info: ------------------------------ 3ms Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::parseTrackInfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: ControllerMpd::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::servicePushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"listen","artist":null,"album":null,"uri":"http://streaming.radio.co:80/s297e618a7/listen","trackType":""} Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: CURRENT POSITION 0 Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::syncState stateService play Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::syncState currentStatus play Oct 15 19:29:27 mano-volumio volumio[1328]: info: Received an update from plugin. extracting info from payload Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:27 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:27.460-04:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%09 @ 0x2380030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:27 mano-volumio volumio[1328]: info: ------------------------------ 9ms Oct 15 19:29:27 mano-volumio volumio[1328]: info: Oct 15 19:29:27 mano-volumio volumio[1328]: ---------------------------- MPD announces system playlist update Oct 15 19:29:27 mano-volumio volumio[1328]: info: Ignoring MPD Status Update Oct 15 19:29:27 mano-volumio volumio[1328]: info: Oct 15 19:29:27 mano-volumio volumio[1328]: ---------------------------- MPD announces state update: player Oct 15 19:29:27 mano-volumio volumio[1328]: info: ControllerMpd::getState Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand status Oct 15 19:29:27 mano-volumio volumio[1328]: info: ------------------------------ 1ms Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand status took 1 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::parseState Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: Oct 15 19:29:27 mano-volumio volumio[1328]: ---------------------------- MPD announces system playlist update Oct 15 19:29:27 mano-volumio volumio[1328]: info: Ignoring MPD Status Update Oct 15 19:29:27 mano-volumio volumio[1328]: info: Oct 15 19:29:27 mano-volumio volumio[1328]: ---------------------------- MPD announces state update: player Oct 15 19:29:27 mano-volumio volumio[1328]: info: ControllerMpd::getState Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand status Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::parseTrackInfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: ControllerMpd::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::servicePushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Wynton Kelly - Come Rain Or Come Shine","artist":"Giants of Jazz Radio","album":null,"uri":"http://streaming.radio.co:80/s297e618a7/listen","trackType":""} Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: CURRENT POSITION 0 Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::syncState stateService play Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::syncState currentStatus play Oct 15 19:29:27 mano-volumio volumio[1328]: info: Received an update from plugin. extracting info from payload Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:27 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:27.482-04:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%09 @ 0x2380030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:27 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:27.482-04:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%09 @ 0x2380030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:27 mano-volumio volumio[1328]: info: ------------------------------ 7ms Oct 15 19:29:27 mano-volumio volumio[1328]: info: ------------------------------ 6ms Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand status took 5 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::parseState Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: ControllerMpd::parseTrackInfo Oct 15 19:29:27 mano-volumio volumio[1328]: info: ControllerMpd::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::servicePushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Wynton Kelly - Come Rain Or Come Shine","artist":"Giants of Jazz Radio","album":null,"uri":"http://streaming.radio.co:80/s297e618a7/listen","trackType":""} Oct 15 19:29:27 mano-volumio volumio[1328]: verbose: CURRENT POSITION 0 Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::syncState stateService play Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::syncState currentStatus play Oct 15 19:29:27 mano-volumio volumio[1328]: info: Received an update from plugin. extracting info from payload Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:27 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:27 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:27 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:27.488-04:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%09 @ 0x2380030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:27 mano-volumio volumio[1328]: info: ------------------------------ 11ms Oct 15 19:29:30 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:30.235-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:29:30 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:30.235-04:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x2380030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 15 19:29:30 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:30.235-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 19:29:30 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:30.235-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id=http://streaming.radio.co:80/s297e618a7/listen title="Giants of Jazz Radio" Oct 15 19:29:33 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:33.578-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:29:33 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:33.578-04:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x2380030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 15 19:29:33 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:33.578-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 19:29:33 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:33.578-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id=http://streaming.radio.co:80/s297e618a7/listen title="Giants of Jazz Radio" Oct 15 19:29:35 mano-volumio volumio[1328]: ------------------------------------ BT MESSAGE: BT STATUS: idle Oct 15 19:29:36 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:36.885-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:29:36 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:36.885-04:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x2380030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 15 19:29:36 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:36.885-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:36 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:36.886-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id=http://streaming.radio.co:80/s297e618a7/listen title="Giants of Jazz Radio" Oct 15 19:29:36 mano-volumio volumio[1328]: ------------------------------------ BT MESSAGE: BT STATUS: pending Oct 15 19:29:36 mano-volumio volumio[1328]: ------------------------------------ BT MESSAGE: BT STATUS: active Oct 15 19:29:36 mano-volumio volumio[1328]: BT PLUGIN MESSAGE: Playing: true Oct 15 19:29:36 mano-volumio volumio[1328]: BT PLUGIN MESSAGE: VOLATILE: undefined Oct 15 19:29:36 mano-volumio volumio[1328]: BT PLUGIN MESSAGE: STATE:undefined Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioStop Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreStateMachine::stop Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreStateMachine::stPlaybackTimer Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreStateMachine::updateTrackBlock Oct 15 19:29:36 mano-volumio volumio[1328]: info: CorePlayQueue::getTrackBlock Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:36 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:36 mano-volumio pulseaudio[1774]: Cannot set requested source latency of 66.67 ms, adjusting to 135.29 ms Oct 15 19:29:36 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:36 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:36 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreStateMachine::serviceStop Oct 15 19:29:36 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::serviceStop Oct 15 19:29:36 mano-volumio volumio[1328]: info: [1792106976949] ControllerWebradio::stop Oct 15 19:29:36 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 19:29:36 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:36.950-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 15 19:29:36 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:36.950-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id=http://streaming.radio.co:80/s297e618a7/listen title="Giants of Jazz Radio" Oct 15 19:29:36 mano-volumio volumio[1328]: info: Oct 15 19:29:36 mano-volumio volumio[1328]: ---------------------------- MPD announces state update: player Oct 15 19:29:36 mano-volumio volumio[1328]: info: sendMpdCommand stop took 5 milliseconds Oct 15 19:29:36 mano-volumio volumio[1328]: info: ControllerMpd::getState Oct 15 19:29:36 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand status Oct 15 19:29:36 mano-volumio volumio[1328]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Oct 15 19:29:36 mano-volumio volumio[1328]: info: Enabling Bluetooth Audio Output Oct 15 19:29:36 mano-volumio volumio[1328]: info: sendMpdCommand status took 9 milliseconds Oct 15 19:29:36 mano-volumio volumio[1328]: verbose: ControllerMpd::parseState Oct 15 19:29:36 mano-volumio volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 19:29:36 mano-volumio volumio[1328]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 15 19:29:36 mano-volumio volumio[1328]: verbose: ControllerMpd::parseTrackInfo Oct 15 19:29:36 mano-volumio volumio[1328]: info: ControllerMpd::pushState Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::servicePushState Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreStateMachine::pushState Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioPushState Oct 15 19:29:36 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output update for this device Oct 15 19:29:36 mano-volumio volumio[1328]: info: MRS: Pushing multiroomSync output Oct 15 19:29:36 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:36 mano-volumio volumio[1328]: info: CorePlayQueue::getTrack 0 Oct 15 19:29:36 mano-volumio volumio[1328]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 15 19:29:36 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:36.967-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_PLAYING positionMs=23068000 volume=100 Oct 15 19:29:36 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:36.967-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id= title= Oct 15 19:29:36 mano-volumio volumio[1328]: info: ------------------------------ 14ms Oct 15 19:29:36 mano-volumio pulseaudio[1774]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead Oct 15 19:29:36 mano-volumio pulseaudio[1774]: Cannot set requested sink latency of 337.51 ms, adjusting to 600.00 ms Oct 15 19:29:36 mano-volumio volumio[1328]: info: Bluetooth Audio successfully Enabled Oct 15 19:29:37 mano-volumio pulseaudio[1774]: Too many underruns, increasing latency to 815.32 ms Oct 15 19:29:40 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:40.191-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:29:40 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:40.191-04:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x2380030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 15 19:29:40 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:40.192-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:40 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:40.192-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id=http://streaming.radio.co:80/s297e618a7/listen title="Giants of Jazz Radio" Oct 15 19:29:43 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:43.514-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:29:43 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:43.514-04:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x2380030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 15 19:29:43 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:43.514-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:43 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:43.514-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id=http://streaming.radio.co:80/s297e618a7/listen title="Wynton Kelly - Come Rain Or Come Shine" Oct 15 19:29:46 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:46.820-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:29:46 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:46.820-04:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x2380030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 15 19:29:46 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:46.820-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:46 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:46.821-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id=http://streaming.radio.co:80/s297e618a7/listen title="Wynton Kelly - Come Rain Or Come Shine" Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.128-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.128-04:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x2380030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.128-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.128-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" id=http://streaming.radio.co:80/s297e618a7/listen title="Wynton Kelly - Come Rain Or Come Shine" Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.502-04:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.86.24:51068 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.502-04:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.86.24:51068 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.505-04:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.86.24:51069 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.505-04:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.86.24:51069 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.520-04:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.24:51088 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.524-04:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.86.24:51089 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.538-04:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.86.24:51088 @ 0x20becc0" latency=11.159231ms timeout=20s Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.538-04:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" Oct 15 19:29:50 mano-volumio volumio[1328]: info: Received Get System Info Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 19:29:50 mano-volumio volumio[1328]: info: Discovery: Getting this device information Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.539-04:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" name="Mano Volumio" Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.540-04:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" language=en Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.540-04:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" timezone=America/New_York Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.541-04:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" available=true connected=true macAddress=2c:cf:67:66:fc:d5 ip4Address=192.168.86.43/24 ip6Address= Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.542-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" available=true connected=true macAddress=2c:cf:67:66:fc:d6 ip4Address=192.168.86.21/24 ip6Address= ssid=GoRedSox Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.543-04:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" setupComplete=true Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 15 19:29:50 mano-volumio volumio[1328]: xcb_connection_has_error() returned true Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 19:29:50 mano-volumio volumio[1328]: amixer -c 0 info | grep "HiFiBerry Digi+ Pro HiFi wm8804-spdif-0" Oct 15 19:29:50 mano-volumio volumio[1328]: xcb_connection_has_error() returned true Oct 15 19:29:50 mano-volumio volumio[1328]: amixer -c 1 info | grep "vc4-hdmi-0" Oct 15 19:29:50 mano-volumio volumio[1328]: xcb_connection_has_error() returned true Oct 15 19:29:50 mano-volumio volumio[1328]: Card hw:1 'vc4hdmi0'/'vc4-hdmi-0' Oct 15 19:29:50 mano-volumio volumio[1328]: amixer -c 2 info | grep "vc4-hdmi-1" Oct 15 19:29:50 mano-volumio volumio[1328]: xcb_connection_has_error() returned true Oct 15 19:29:50 mano-volumio volumio[1328]: Card hw:2 'vc4hdmi1'/'vc4-hdmi-1' Oct 15 19:29:50 mano-volumio volumio[1328]: xcb_connection_has_error() returned true Oct 15 19:29:50 mano-volumio volumio[1328]: amixer -c 0 info | grep "snd_rpi_hifiberry_digi" Oct 15 19:29:50 mano-volumio volumio[1328]: xcb_connection_has_error() returned true Oct 15 19:29:50 mano-volumio volumio[1328]: Card hw:0 'sndrpihifiberry'/'snd_rpi_hifiberry_digi' Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.663-04:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" selectedOutputId=0 Oct 15 19:29:50 mano-volumio volumio[1328]: info: Received Get System Info Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 19:29:50 mano-volumio volumio[1328]: info: Discovery: Getting this device information Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.672-04:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" currentVersion=3.912 latestVersion=3.912 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.672-04:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.86.24:51088 @ 0x20becc0" status=UPDATE_STATUS_NONE progress=0 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.672-04:00 level=INFO msg="emitting user changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" userId= Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.672-04:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" providers=3 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.672-04:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" plugins=54 Oct 15 19:29:50 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.673-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" state=STATUS_PLAYING positionMs=23082000 volume=100 Oct 15 19:29:50 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:50.673-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.24:51088 @ 0x20becc0" id= title= Oct 15 19:29:51 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:51.574-04:00 level=INFO msg="new address was allocated" component=ble/conn old=11 new=12 Oct 15 19:29:53 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:53.447-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:29:53 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:53.447-04:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%09 @ 0x2380030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Oct 15 19:29:53 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:53.447-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.24:51068,192.168.86.24:51069,00:00:00:00:00:00%0a @ 0x24d7da0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 15 19:29:56 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:29:56.799-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.107-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.159-04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=13.976738ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.174-04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.189-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=15.386411ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.190-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=16.509625ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.193-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=19.124722ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.210-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=36.264963ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.215-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://securetoken.googleapis.com duration=40.862318ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.228-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://functions.volumio.cloud duration=54.541641ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.237-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://functions.volumio.cloud duration=63.591183ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.269-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=http://plugins.volumio.org duration=94.748583ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.269-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://database.volumio.cloud duration=94.78938ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.279-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=http://pushupdates.volumio.org duration=105.334676ms Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.280-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://www.googleapis.com duration=105.909773ms Oct 15 19:30:00 mano-volumio sudo[14161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 19:30:00 mano-volumio sudo[14161]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 19:30:00 mano-volumio sudo[14161]: pam_unix(sudo:session): session closed for user root Oct 15 19:30:00 mano-volumio sudo[14164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 19:30:00 mano-volumio sudo[14164]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 19:30:00 mano-volumio sudo[14164]: pam_unix(sudo:session): session closed for user root Oct 15 19:30:00 mano-volumio volumio[1328]: verbose: New Socket.io Connection to 192.168.86.43 from 192.168.86.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.522-04:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=http://cddb.volumio.org duration=348.231234ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 19:30:00 mano-volumio sudo[14167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 19:30:00 mano-volumio sudo[14167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 19:30:00 mano-volumio sudo[14167]: pam_unix(sudo:session): session closed for user root Oct 15 19:30:00 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:00.608-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.86.24:51088,192.168.86.24:51089,00:00:00:00:00:00%0b @ 0x20becc0" latency=11.038633ms timeout=10s endpoint=https://google.com duration=433.988554ms Oct 15 19:30:00 mano-volumio sudo[14170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 19:30:00 mano-volumio sudo[14170]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 15 19:30:00 mano-volumio sudo[14170]: pam_unix(sudo:session): session closed for user root Oct 15 19:30:00 mano-volumio volumio[1328]: verbose: New Socket.io Connection to 192.168.86.43 from 192.168.86.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 15 19:30:00 mano-volumio volumio[1328]: info: CoreCommandRouter::volumioGetState Oct 15 19:30:00 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 19:30:00 mano-volumio volumio[1328]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 15 19:30:00 mano-volumio volumio[1328]: info: Listing playlists Oct 15 19:30:00 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 19:30:00 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 19:30:00 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 19:30:00 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 19:30:03 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:03.473-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:30:06 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:06.807-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:30:10 mano-volumio volumio5-onboarding[1450]: time=2026-10-15T19:30:10.116-04:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 15 19:30:10 mano-volumio volumiologrotate[762]: ls: cannot access '/var/log/samba/log.wb-MANO': No such file or directory Oct 15 19:30:10 mano-volumio volumiologrotate[762]: ls: cannot access 'VOLUMIO': No such file or directory Oct 15 19:30:11 mano-volumio volumio[1328]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Oct 15 19:30:11 mano-volumio volumio[1328]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Oct 15 19:30:11 mano-volumio volumio[1328]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 19:30:11 mano-volumio volumio[1328]: TypeError: Cannot read property 'then' of undefined Oct 15 19:30:11 mano-volumio volumio[1328]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Oct 15 19:30:11 mano-volumio volumio[1328]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Oct 15 19:30:11 mano-volumio volumio[1328]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Oct 15 19:30:11 mano-volumio volumio[1328]: at Socket.emit (events.js:315:20) Oct 15 19:30:11 mano-volumio volumio[1328]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 15 19:30:11 mano-volumio volumio[1328]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 15 19:30:11 mano-volumio volumio[1328]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 19:30:11 mano-volumio sudo[14237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-15 19:29 Oct 15 19:30:11 mano-volumio sudo[14237]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"