Mar 28 21:04:00 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 21:04:00 volumio volumio[1272]: info: Received Get System Info Mar 28 21:04:00 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 21:04:00 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 21:04:00 volumio volumio[1272]: info: Discovery: Getting this device information Mar 28 21:04:00 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Mar 28 21:04:00 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:04:00 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 21:04:00 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 21:04:01 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 21:04:01 volumio volumio[1272]: info: Received Get System Info Mar 28 21:04:01 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 21:04:01 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 21:04:01 volumio volumio[1272]: info: Discovery: Getting this device information Mar 28 21:04:01 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Mar 28 21:04:01 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:04:01 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 21:04:04 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 21:04:04 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 21:04:08 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 21:04:08 volumio volumio[1272]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 21:04:08 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 21:04:08 volumio volumio[1272]: info: Received Get System Version Mar 28 21:04:08 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 21:04:08 volumio volumio[1272]: info: Received Get System Info Mar 28 21:04:08 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 21:04:08 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 21:04:08 volumio volumio[1272]: info: Discovery: Getting this device information Mar 28 21:04:08 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Mar 28 21:04:08 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:04:08 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 21:04:08 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 28 21:04:10 volumio volumio[1272]: info: CoreCommandRouter::Close All Modals sent Mar 28 21:04:10 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Mar 28 21:04:16 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 21:04:16 volumio volumio[1272]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 21:04:16 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 21:04:16 volumio volumio[1272]: info: Received Get System Version Mar 28 21:04:16 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 21:04:16 volumio volumio[1272]: info: Received Get System Info Mar 28 21:04:16 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 21:04:16 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 21:04:16 volumio volumio[1272]: info: Discovery: Getting this device information Mar 28 21:04:16 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Mar 28 21:04:16 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:04:16 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 21:04:18 volumio volumio[1272]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/bookworm/armhf Mar 28 21:04:18 volumio volumio5-onboarding[1473]: time=2026-03-28T21:04:18.552+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 21:04:23 volumio volumio[1272]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/youtube2/volumio/bookworm/armhf Mar 28 21:04:23 volumio volumio[1272]: info: Folder /tmp/plugins removed Mar 28 21:04:23 volumio volumio5-onboarding[1473]: time=2026-03-28T21:04:23.063+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 21:04:29 volumio volumio5-onboarding[1473]: time=2026-03-28T21:04:29.791+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 21:04:29 volumio volumio[1272]: info: Check plugin dependencies Mar 28 21:04:30 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 21:04:30 volumio volumio5-onboarding[1473]: time=2026-03-28T21:04:30.269+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 21:04:30 volumio volumio[1272]: info: Checking if plugin already exists Mar 28 21:04:30 volumio volumio5-onboarding[1473]: time=2026-03-28T21:04:30.304+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 21:04:30 volumio volumio[1272]: info: Rename folder Mar 28 21:04:30 volumio volumio5-onboarding[1473]: time=2026-03-28T21:04:30.382+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 21:04:30 volumio volumio[1272]: info: Move to category Mar 28 21:04:30 volumio volumio[1272]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 21:04:31 volumio volumio5-onboarding[1473]: time=2026-03-28T21:04:31.945+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 21:04:32 volumio volumio[1272]: info: Checking if install.sh is present Mar 28 21:04:32 volumio volumio[1272]: info: Check return the error Error: ENOENT: no such file or directory, stat '/data/plugins/music_service/youtube2/install.sh' Mar 28 21:04:32 volumio volumio5-onboarding[1473]: time=2026-03-28T21:04:32.272+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 21:04:32 volumio volumio[1272]: info: An error occurred installing the plugin. Rolling back config Mar 28 21:04:32 volumio volumio[1272]: info: Plugin folders cleanup Mar 28 21:04:32 volumio volumio[1272]: info: Scanning into folder /volumio/app/plugins/ Mar 28 21:04:32 volumio volumio[1272]: info: Scanning category audio_interface Mar 28 21:04:32 volumio volumio[1272]: info: Scanning category miscellanea Mar 28 21:04:32 volumio volumio[1272]: info: Scanning category music_service Mar 28 21:04:32 volumio volumio[1272]: info: Scanning category plugins.json Mar 28 21:04:32 volumio volumio[1272]: info: Scanning category system_controller Mar 28 21:04:32 volumio volumio[1272]: info: Scanning category user_interface Mar 28 21:04:32 volumio volumio[1272]: info: Scanning into folder /data/plugins/ Mar 28 21:04:32 volumio volumio[1272]: info: Scanning category music_service Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/README.md Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/config.json Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/eslint.config.mjs Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/fixup.sh Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/gitignore Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/install.sh Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/package-lock.json Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/package.json Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/tsconfig.json Mar 28 21:04:32 volumio volumio[1272]: info: Removing /data/plugins//music_service/uninstall.sh Mar 28 21:04:32 volumio volumio[1272]: info: Plugin folders cleanup completed Mar 28 21:04:32 volumio volumio[1272]: info: Error: Error Mar 28 21:04:32 volumio volumio[1272]: info: Folder /tmp/plugins removed Mar 28 21:04:32 volumio volumio[1272]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 21:04:32 volumio volumio[1272]: info: Folder /data/temp removed Mar 28 21:04:34 volumio avahi-daemon[811]: Record [04255CB411F0\064Volumio._raop._tcp.local IN SRV 0 0 5000 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [_Volumio._tcp.local IN PTR Volumio._Volumio._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [Volumio._Volumio._tcp.local IN TXT "volumioName=Volumio" "UUID=c494b56d-7f3b-407a-9b6b-97a139c7a99f" ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [Volumio._Volumio._tcp.local IN SRV 0 0 3000 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [_device-info._tcp.local IN PTR VOLUMIO._device-info._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [VOLUMIO._device-info._tcp.local IN TXT "model=AirPort" ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [VOLUMIO._device-info._tcp.local IN SRV 0 0 0 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [_smb._tcp.local IN PTR VOLUMIO._smb._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [VOLUMIO._smb._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 21:04:34 volumio avahi-daemon[811]: Record [VOLUMIO._smb._tcp.local IN SRV 0 0 445 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. Mar 28 21:04:45 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Mar 28 21:04:45 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:04:46 volumio volumio[1272]: info: CoreCommandRouter::volumioGetQueue Mar 28 21:04:46 volumio volumio[1272]: info: CoreStateMachine::getQueue Mar 28 21:04:46 volumio volumio[1272]: info: CorePlayQueue::getQueue Mar 28 21:04:47 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 21:04:47 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 21:04:48 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:04:50 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 21:04:50 volumio volumio[1272]: info: TuneIn handleBrowseUri: tunein/local Mar 28 21:04:51 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:04:57 volumio volumio[1272]: info: Saving local item radio-favourites Mar 28 21:04:57 volumio volumio[1272]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s8707","favourite":false} Mar 28 21:05:06 volumio volumio[1272]: info: Saving local item radio-favourites Mar 28 21:05:06 volumio volumio[1272]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s233454","favourite":false} Mar 28 21:05:33 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 21:05:33 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:05:34 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:05:34 volumio volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 21:05:34 volumio volumio[1272]: info: CoreStateMachine::ClearQueue Mar 28 21:05:34 volumio volumio[1272]: info: CoreStateMachine::stop Mar 28 21:05:34 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:34 volumio volumio[1272]: info: CorePlayQueue::clearPlayQueue Mar 28 21:05:34 volumio volumio[1272]: info: CorePlayQueue::saveQueue Mar 28 21:05:34 volumio volumio[1272]: info: CoreCommandRouter::volumioPushQueue Mar 28 21:05:34 volumio volumio[1272]: info: CoreStateMachine::addQueueItems Mar 28 21:05:34 volumio volumio[1272]: info: CorePlayQueue::addQueueItems Mar 28 21:05:34 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:05:34 volumio volumio[1272]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s8707 Mar 28 21:05:34 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::volumioPushQueue Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::saveQueue Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::volumioPlay Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::play index 0 Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::stop Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::play index undefined Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::startPlaybackTimer Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:35 volumio volumio[1272]: info: [1774728335196] ControllerWebradio::clearAddPlayTrack Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand stop took 2 milliseconds Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand clear took 2 milliseconds Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand load "http://bestfmdebrecen.hu/fm95.m3u" Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: error: updateQueue error: null Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 5ms Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand load "http://bestfmdebrecen.hu/fm95.m3u" took 49 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 49ms Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 48ms Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand play Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 5ms Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand play took 67 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 67ms Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 66ms Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 3 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 79 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 78 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 133 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 61 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 59 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 58 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 58ms Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 58 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 57 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 57 milliseconds Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:35 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:35 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:35 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:35 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:35 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:35 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:35 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:35 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 223ms Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.758+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.758+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.758+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.758+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.801+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.801+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.802+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.802+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.804+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.804+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.804+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.804+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.811+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.811+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.812+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:35 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:35.812+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 339ms Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 339ms Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:35 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:35 volumio volumio[1272]: info: Mar 28 21:05:35 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 261ms Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 259 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 259 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 251 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 250 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 250 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 57ms Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 10 milliseconds Mar 28 21:05:35 volumio volumio[1272]: info: ------------------------------ 10ms Mar 28 21:05:35 volumio volumio[1272]: info: sendMpdCommand status took 9 milliseconds Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:35 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:35 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:35 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:35 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:35 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:35 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:35 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:35 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:35 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:35 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:36 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:36 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:36 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:36 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:36 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:36 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:36 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:36 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.071+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.071+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.071+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.072+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.073+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.073+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.073+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.073+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.104+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.104+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.104+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.104+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.110+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.111+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.111+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.112+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.112+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.112+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.113+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.113+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.114+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.114+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.114+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.115+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.116+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.116+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.116+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.116+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.131+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.131+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=484 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.131+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.132+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio[1272]: info: ------------------------------ 750ms Mar 28 21:05:36 volumio volumio[1272]: info: ------------------------------ 674ms Mar 28 21:05:36 volumio volumio[1272]: info: ------------------------------ 674ms Mar 28 21:05:36 volumio volumio[1272]: info: ------------------------------ 673ms Mar 28 21:05:36 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 540 milliseconds Mar 28 21:05:36 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 540 milliseconds Mar 28 21:05:36 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 539 milliseconds Mar 28 21:05:36 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:36 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:36 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:36 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:36 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:36 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:36 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:36 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:36 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:36 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1226,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:36 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:36 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:36 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:36 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1226,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"32 Kbps","isStreaming":false,"title":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:36 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:36 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:36 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.583+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.584+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.584+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.584+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.590+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.590+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.591+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.591+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.592+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.592+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.592+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.592+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.611+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.611+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.611+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.611+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.612+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.612+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.613+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.613+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.620+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.620+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=1027 volume=86 Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.621+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:36.621+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://stream.webthings.hu:8000/fm95-x-128.mp3 title="Shania Twain-That Don\\'t Impress Me Much" Mar 28 21:05:36 volumio volumio[1272]: info: ------------------------------ 1019ms Mar 28 21:05:36 volumio volumio[1272]: info: ------------------------------ 854ms Mar 28 21:05:36 volumio volumio[1272]: info: ------------------------------ 806ms Mar 28 21:05:39 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::ClearQueue Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::stop Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::serviceStop Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::serviceStop Mar 28 21:05:39 volumio volumio[1272]: info: [1774728339466] ControllerWebradio::stop Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::clearPlayQueue Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::saveQueue Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushQueue Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::addQueueItems Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::addQueueItems Mar 28 21:05:39 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:05:39 volumio volumio[1272]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s233454 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.469+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.469+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.469+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://bestfmdebrecen.hu/fm95.m3u title="Best FM" Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.470+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://bestfmdebrecen.hu/fm95.m3u title="Best FM" Mar 28 21:05:39 volumio volumio[1272]: info: Mar 28 21:05:39 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:39 volumio volumio[1272]: info: sendMpdCommand stop took 124 milliseconds Mar 28 21:05:39 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:39 volumio volumio[1272]: info: Mar 28 21:05:39 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:39 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:39 volumio volumio[1272]: info: Mar 28 21:05:39 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:39 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:39 volumio volumio[1272]: info: sendMpdCommand status took 3 milliseconds Mar 28 21:05:39 volumio volumio[1272]: info: sendMpdCommand status took 3 milliseconds Mar 28 21:05:39 volumio volumio[1272]: info: sendMpdCommand status took 2 milliseconds Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:39 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 28 21:05:39 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 28 21:05:39 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:39 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:39 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: 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":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:39 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::syncState stateService stop Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: No code Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: 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":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:39 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::syncState stateService stop Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: No code Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: 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":"Shania Twain-That Don\\'t Impress Me Much","artist":"BestFM - a zene radioja - hosted by gomedia.hu","album":null,"uri":"http://stream.webthings.hu:8000/fm95-x-128.mp3","trackType":"mp3"} Mar 28 21:05:39 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::syncState stateService stop Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio[1272]: info: No code Mar 28 21:05:39 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:39 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:39 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.752+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.752+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.752+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.752+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.771+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.771+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.771+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.771+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.772+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.772+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.773+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.773+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.780+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.781+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.781+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.781+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.783+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.783+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.783+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.783+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.800+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.800+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.801+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.801+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.802+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.803+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.803+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.803+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.830+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.831+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.831+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.831+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.832+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.833+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.833+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id= title= Mar 28 21:05:39 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:39.833+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id= title= Mar 28 21:05:39 volumio volumio[1272]: info: ------------------------------ 431ms Mar 28 21:05:39 volumio volumio[1272]: info: ------------------------------ 404ms Mar 28 21:05:39 volumio volumio[1272]: info: ------------------------------ 403ms Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushQueue Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::saveQueue Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPlay Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::play index 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::stop Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::play index undefined Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::startPlaybackTimer Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: info: [1774728340245] ControllerWebradio::clearAddPlayTrack Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand stop took 35 milliseconds Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand clear took 20 milliseconds Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand load "http://91.82.85.44:8080/debrecenradiofm95" Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: error: updateQueue error: null Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 6ms Mar 28 21:05:40 volumio volumio[1272]: error: updateQueue error: null Mar 28 21:05:40 volumio volumio[1272]: error: updateQueue error: null Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand add "http://91.82.85.44:8080/debrecenradiofm95" Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 181ms Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 180ms Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand add "http://91.82.85.44:8080/debrecenradiofm95" took 2 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand play Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 6ms Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand play took 4 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 4ms Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 3ms Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 6 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:40 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:40 volumio volumio[1272]: info: Mar 28 21:05:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 36 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 62 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 62 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 62 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 52 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 51 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 50ms Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 55 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 54ms Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 40 milliseconds Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: 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":"debrecenradiofm95","artist":null,"album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:40 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 100ms Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 61ms Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 31 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: ------------------------------ 61ms Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand status took 30 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 28 21:05:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: 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":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:40 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: 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":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:40 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: 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":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:40 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:40 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:40 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:40 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:40 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.919+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.919+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.920+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.920+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.976+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.977+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.977+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.977+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.977+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.977+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.977+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.977+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.978+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.978+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.978+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.978+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.978+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.978+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.978+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.978+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.979+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.979+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.979+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.979+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.979+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.979+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.979+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.980+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.980+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.980+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.980+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:40 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.979+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.999+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.999+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.999+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:40.999+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.002+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.002+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.002+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.002+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.021+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.021+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.022+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.022+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.024+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.024+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.025+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.025+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.040+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.041+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.041+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.041+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.043+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.044+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.044+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.045+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 764ms Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 803ms Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 803ms Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 802ms Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 791ms Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 790ms Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 793ms Mar 28 21:05:41 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 718 milliseconds Mar 28 21:05:41 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 726 milliseconds Mar 28 21:05:41 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:41 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:41 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:41 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:41 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:41 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:41 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:41 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:41 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:41 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:41 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:41 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:41 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:41 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:41 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:41 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:41 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:41 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:41 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:41 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:41 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:41 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:41 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:41 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:41 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:41 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:41 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.534+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=957 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.534+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=957 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.534+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.534+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.541+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=957 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.541+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=957 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.542+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.542+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.560+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=957 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.560+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=957 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.561+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.561+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.563+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=957 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.563+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=957 volume=86 Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.563+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:41.563+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 946ms Mar 28 21:05:41 volumio volumio[1272]: info: ------------------------------ 987ms Mar 28 21:05:46 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::ClearQueue Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::stop Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::serviceStop Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::serviceStop Mar 28 21:05:46 volumio volumio[1272]: info: [1774728346396] ControllerWebradio::stop Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::clearPlayQueue Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::saveQueue Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::volumioPushQueue Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::addQueueItems Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::addQueueItems Mar 28 21:05:46 volumio volumio[1272]: info: Preload queue cleared Mar 28 21:05:46 volumio volumio[1272]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s233454 Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.401+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.402+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=0 volume=86 Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.402+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="Rádió 1 Debrecen" Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.403+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="Rádió 1 Debrecen" Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::volumioPushQueue Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::saveQueue Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::volumioPlay Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::play index 0 Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::stop Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::play index undefined Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::startPlaybackTimer Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:46 volumio volumio[1272]: info: [1774728346483] ControllerWebradio::clearAddPlayTrack Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand stop took 123 milliseconds Mar 28 21:05:46 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand stop took 31 milliseconds Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:46 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:46 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:46 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand status took 12 milliseconds Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand clear took 11 milliseconds Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand status took 9 milliseconds Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand status took 2 milliseconds Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand load "http://91.82.85.44:8080/debrecenradiofm95" Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:46 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:46 volumio volumio[1272]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 21:05:46 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:46 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:46 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:46 volumio volumio[1272]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.583+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=7 volume=86 Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.583+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=7 volume=86 Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.584+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="Rádió 1 Debrecen" Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.584+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="Rádió 1 Debrecen" Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.591+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_STOPPED positionMs=7 volume=86 Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.591+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_STOPPED positionMs=7 volume=86 Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.591+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="Rádió 1 Debrecen" Mar 28 21:05:46 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:46.591+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="Rádió 1 Debrecen" Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 75ms Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 109ms Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:46 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:46 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:46 volumio volumio[1272]: error: updateQueue error: null Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 109ms Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 108 milliseconds Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:46 volumio volumio[1272]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 148ms Mar 28 21:05:46 volumio volumio[1272]: error: updateQueue error: null Mar 28 21:05:46 volumio volumio[1272]: error: updateQueue error: null Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand add "http://91.82.85.44:8080/debrecenradiofm95" Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 162ms Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 161ms Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:46 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand add "http://91.82.85.44:8080/debrecenradiofm95" took 2 milliseconds Mar 28 21:05:46 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 21:05:46 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand play Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:46 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:46 volumio volumio[1272]: info: Mar 28 21:05:46 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:46 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 4ms Mar 28 21:05:46 volumio volumio[1272]: info: sendMpdCommand play took 3 milliseconds Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 11ms Mar 28 21:05:46 volumio volumio[1272]: info: ------------------------------ 10ms Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 10 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:47 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:47 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:47 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:47 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 40 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 41 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 42 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 41 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 33 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 32 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 32ms Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 25 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 29ms Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 27 milliseconds Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: 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":"debrecenradiofm95","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 78ms Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Mar 28 21:05:47 volumio volumio[1272]: info: Ignoring MPD Status Update Mar 28 21:05:47 volumio volumio[1272]: info: Mar 28 21:05:47 volumio volumio[1272]: ---------------------------- MPD announces state update: player Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::getState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 57ms Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 42 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 51ms Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 39 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 33 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 17ms Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand status took 5 milliseconds Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseState Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: 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":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":400,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.222+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.222+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.222+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.222+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.224+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.224+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.224+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.224+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.228+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.228+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.228+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.228+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.228+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.228+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.228+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.228+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.250+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.251+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.251+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.251+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.252+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.253+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.255+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.259+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.260+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.260+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.260+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.260+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.260+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.260+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.260+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.261+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.263+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.263+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.263+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.263+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.264+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.265+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.265+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.265+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.269+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.270+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.270+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.270+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.271+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.271+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.271+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.272+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.273+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.274+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.274+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.274+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.301+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.301+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=0 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.301+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.301+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 588ms Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 587ms Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 582ms Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 579ms Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 571ms Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 569ms Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 567ms Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 511 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 523 milliseconds Mar 28 21:05:47 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 519 milliseconds Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: ControllerMpd::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Mar 28 21:05:47 volumio volumio[1272]: info: CorePlayQueue::getTrack 0 Mar 28 21:05:47 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS","artist":"DebrecenRadioFM95","album":null,"uri":"http://91.82.85.44:8080/debrecenradiofm95","trackType":""} Mar 28 21:05:47 volumio volumio[1272]: verbose: CURRENT POSITION 0 Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Mar 28 21:05:47 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreStateMachine::pushState Mar 28 21:05:47 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.728+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.729+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.729+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.729+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.743+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.743+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.743+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.744+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.744+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.744+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.744+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.744+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.746+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.746+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.747+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.747+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.760+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.760+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.761+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.761+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.772+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.773+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" state=STATUS_PLAYING positionMs=625 volume=86 Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.780+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.130:48364 @ 0x33c4990" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio5-onboarding[1473]: time=2026-03-28T21:05:47.780+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.130:48364 @ 0x3000750" id=http://91.82.85.44:8080/debrecenradiofm95 title="FM95 R?di? 1 - WORLD IS MINE RADIO SHOW - LOVING ARMS" Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 797ms Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 811ms Mar 28 21:05:47 volumio volumio[1272]: info: ------------------------------ 775ms Mar 28 21:05:52 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Mar 28 21:05:53 volumio volumio[1272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 21:05:53 volumio volumio[1272]: TypeError: Cannot read properties of undefined (reading 'plugin_type') Mar 28 21:05:53 volumio volumio[1272]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Mar 28 21:05:53 volumio volumio[1272]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29) Mar 28 21:05:53 volumio volumio[1272]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1353:45) Mar 28 21:05:53 volumio volumio[1272]: at Socket.emit (node:events:514:28) Mar 28 21:05:53 volumio volumio[1272]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 28 21:05:53 volumio volumio[1272]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 28 21:05:53 volumio volumio[1272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 21:05:56 volumio sudo[2201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 21:04' Mar 28 21:05:56 volumio sudo[2201]: 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"