Mar 28 18:51:00 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:00 volumio sudo[1809]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 18:51:00 volumio sudo[1809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:00 volumio sudo[1809]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:00 volumio volumio[1215]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.130 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 28 18:51:00 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:00.311+01:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Mar 28 18:51:00 volumio sudo[1813]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 18:51:00 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:00 volumio sudo[1815]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 18:51:00 volumio sudo[1815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:00 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:00 volumio sudo[1815]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:00 volumio volumio[1215]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.130 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:01 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 18:51:01 volumio volumio[1215]: info: Received Get System Info Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:01 volumio volumio[1215]: info: Discovery: Getting this device information Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:01 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:01 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:01 volumio volumio[1215]: info: Listing playlists Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 18:51:01 volumio sudo[1823]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 18:51:01 volumio sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 18:51:01 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:01 volumio sudo[1823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:01 volumio sudo[1823]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:01 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:01 volumio volumio[1215]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.130 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 28 18:51:01 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 18:51:02 volumio sudo[1828]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 18:51:02 volumio sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:02 volumio sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 18:51:02 volumio sudo[1830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 18:51:02 volumio sudo[1828]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:02 volumio sudo[1830]: pam_unix(sudo:session): session closed for user root Mar 28 18:51:02 volumio volumio[1215]: verbose: New Socket.io Connection to 192.168.1.239 from 192.168.1.130 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:02 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 18:51:02 volumio volumio[1215]: info: Received Get System Info Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:02 volumio volumio[1215]: info: Discovery: Getting this device information Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:02 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:02 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:02 volumio volumio[1215]: info: Listing playlists Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 18:51:02 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 18:51:04 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:51:04 volumio volumio[1215]: info: Received Get System Info Mar 28 18:51:04 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:04 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:04 volumio volumio[1215]: info: Discovery: Getting this device information Mar 28 18:51:04 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:04 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:04 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:04 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 18:51:05 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:51:05 volumio volumio[1215]: info: Received Get System Info Mar 28 18:51:05 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:05 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:05 volumio volumio[1215]: info: Discovery: Getting this device information Mar 28 18:51:05 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:05 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:05 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:12 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 28 18:51:18 volumio volumio[1215]: info: CoreCommandRouter::volumioGetQueue Mar 28 18:51:18 volumio volumio[1215]: info: CoreStateMachine::getQueue Mar 28 18:51:18 volumio volumio[1215]: info: CorePlayQueue::getQueue Mar 28 18:51:20 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 18:51:20 volumio volumio[1215]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 18:51:20 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 18:51:20 volumio volumio[1215]: info: Received Get System Version Mar 28 18:51:20 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 18:51:20 volumio volumio[1215]: info: Received Get System Info Mar 28 18:51:20 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 18:51:20 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 18:51:20 volumio volumio[1215]: info: Discovery: Getting this device information Mar 28 18:51:20 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Mar 28 18:51:20 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:20 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 18:51:24 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 18:51:25 volumio volumio[1215]: info: Preload queue cleared Mar 28 18:51:26 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 28 18:51:26 volumio volumio[1215]: info: Preload queue cleared Mar 28 18:51:28 volumio volumio[1215]: info: Preload queue cleared Mar 28 18:51:28 volumio volumio[1215]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::ClearQueue Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::stop Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:28 volumio volumio[1215]: info: CorePlayQueue::clearPlayQueue Mar 28 18:51:28 volumio volumio[1215]: info: CorePlayQueue::saveQueue Mar 28 18:51:28 volumio volumio[1215]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::addQueueItems Mar 28 18:51:28 volumio volumio[1215]: info: CorePlayQueue::addQueueItems Mar 28 18:51:28 volumio volumio[1215]: info: Preload queue cleared Mar 28 18:51:28 volumio volumio[1215]: info: Adding Item to queue: http://stream.webthings.hu:8000/fm95-x-128.mp3 Mar 28 18:51:28 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 18:51:28 volumio volumio[1215]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:28 volumio volumio[1215]: info: CorePlayQueue::saveQueue Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::updateTrackBlock Mar 28 18:51:28 volumio volumio[1215]: info: CorePlayQueue::getTrackBlock Mar 28 18:51:28 volumio volumio[1215]: info: CoreCommandRouter::volumioPlay Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::play index 0 Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::stop Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::play index undefined Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:28 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::startPlaybackTimer Mar 28 18:51:28 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:28 volumio volumio[1215]: info: [1774720288534] ControllerWebradio::clearAddPlayTrack Mar 28 18:51:28 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:28 volumio volumio[1215]: info: sendMpdCommand stop took 64 milliseconds Mar 28 18:51:28 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 18:51:28 volumio volumio[1215]: info: Mar 28 18:51:28 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:28 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:28 volumio volumio[1215]: info: sendMpdCommand clear took 2 milliseconds Mar 28 18:51:28 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand load "http://stream.webthings.hu:8000/fm95-x-128.mp3" Mar 28 18:51:28 volumio volumio[1215]: info: Mar 28 18:51:28 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:28 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:28 volumio volumio[1215]: info: Mar 28 18:51:28 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:28 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:28 volumio volumio[1215]: error: updateQueue error: null Mar 28 18:51:28 volumio volumio[1215]: info: ------------------------------ 5ms Mar 28 18:51:28 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand add "http://stream.webthings.hu:8000/fm95-x-128.mp3" Mar 28 18:51:28 volumio volumio[1215]: error: updateQueue error: null Mar 28 18:51:28 volumio volumio[1215]: error: updateQueue error: null Mar 28 18:51:28 volumio volumio[1215]: info: Mar 28 18:51:28 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:28 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:28 volumio volumio[1215]: info: ------------------------------ 376ms Mar 28 18:51:28 volumio volumio[1215]: info: ------------------------------ 375ms Mar 28 18:51:28 volumio volumio[1215]: info: sendMpdCommand add "http://stream.webthings.hu:8000/fm95-x-128.mp3" took 2 milliseconds Mar 28 18:51:28 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 18:51:28 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand play Mar 28 18:51:28 volumio volumio[1215]: info: Mar 28 18:51:28 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:28 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:28 volumio volumio[1215]: info: Mar 28 18:51:28 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:28 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:28 volumio volumio[1215]: info: ------------------------------ 5ms Mar 28 18:51:28 volumio volumio[1215]: info: sendMpdCommand play took 4 milliseconds Mar 28 18:51:28 volumio volumio[1215]: info: ------------------------------ 3ms Mar 28 18:51:28 volumio volumio[1215]: info: ------------------------------ 2ms Mar 28 18:51:29 volumio volumio[1215]: info: Mar 28 18:51:29 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:29 volumio volumio[1215]: info: Mar 28 18:51:29 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:29 volumio volumio[1215]: info: Mar 28 18:51:29 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:29 volumio volumio[1215]: info: Mar 28 18:51:29 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:29 volumio volumio[1215]: info: sendMpdCommand status took 2 milliseconds Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:29 volumio volumio[1215]: info: sendMpdCommand status took 2 milliseconds Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:29 volumio volumio[1215]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 18:51:29 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState stateService stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: No code Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:29 volumio volumio[1215]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 18:51:29 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState stateService stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: No code Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:29.708+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:29 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:29.709+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:29 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:29.727+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:29 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:29.728+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:29 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:29.730+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:29 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:29.731+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:29 volumio volumio[1215]: info: ------------------------------ 277ms Mar 28 18:51:29 volumio volumio[1215]: info: ------------------------------ 277ms Mar 28 18:51:29 volumio volumio[1215]: info: Mar 28 18:51:29 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:29 volumio volumio[1215]: info: Mar 28 18:51:29 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:29 volumio volumio[1215]: info: sendMpdCommand status took 328 milliseconds Mar 28 18:51:29 volumio volumio[1215]: info: sendMpdCommand status took 328 milliseconds Mar 28 18:51:29 volumio volumio[1215]: info: sendMpdCommand status took 2 milliseconds Mar 28 18:51:29 volumio volumio[1215]: info: sendMpdCommand status took 1 milliseconds Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:29 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:29 volumio volumio[1215]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 18:51:29 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState stateService stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: No code Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:29 volumio volumio[1215]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 18:51:29 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState stateService stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: No code Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:29 volumio volumio[1215]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 18:51:29 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState stateService stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: No code Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:29 volumio volumio[1215]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 28 18:51:29 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState stateService stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus stop Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:29 volumio volumio[1215]: info: No code Mar 28 18:51:29 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:29 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.064+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.068+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.077+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.108+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.109+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.117+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.118+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.119+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.121+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.122+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.137+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:30.138+01:00 level=WARN msg="received unknown player status" component=volumio status="" Mar 28 18:51:30 volumio volumio[1215]: info: ------------------------------ 754ms Mar 28 18:51:30 volumio volumio[1215]: info: ------------------------------ 755ms Mar 28 18:51:30 volumio volumio[1215]: info: ------------------------------ 479ms Mar 28 18:51:30 volumio volumio[1215]: info: ------------------------------ 428ms Mar 28 18:51:34 volumio volumio[1215]: info: Preload queue cleared Mar 28 18:51:34 volumio volumio[1215]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::ClearQueue Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::stop Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:34 volumio volumio[1215]: info: CorePlayQueue::clearPlayQueue Mar 28 18:51:34 volumio volumio[1215]: info: CorePlayQueue::saveQueue Mar 28 18:51:34 volumio volumio[1215]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::addQueueItems Mar 28 18:51:34 volumio volumio[1215]: info: CorePlayQueue::addQueueItems Mar 28 18:51:34 volumio volumio[1215]: info: Preload queue cleared Mar 28 18:51:34 volumio volumio[1215]: info: Adding Item to queue: http://stream.webthings.hu:8000/fm95-x-128.mp3 Mar 28 18:51:34 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 28 18:51:34 volumio volumio[1215]: info: CoreCommandRouter::volumioPushQueue Mar 28 18:51:34 volumio volumio[1215]: info: CorePlayQueue::saveQueue Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::updateTrackBlock Mar 28 18:51:34 volumio volumio[1215]: info: CorePlayQueue::getTrackBlock Mar 28 18:51:34 volumio volumio[1215]: info: CoreCommandRouter::volumioPlay Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::play index 0 Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::stop Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::play index undefined Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:34 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::startPlaybackTimer Mar 28 18:51:34 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:34 volumio volumio[1215]: info: [1774720294560] ControllerWebradio::clearAddPlayTrack Mar 28 18:51:34 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:34 volumio volumio[1215]: info: sendMpdCommand stop took 3 milliseconds Mar 28 18:51:34 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 18:51:34 volumio volumio[1215]: info: sendMpdCommand clear took 0 milliseconds Mar 28 18:51:34 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand load "http://stream.webthings.hu:8000/fm95-x-128.mp3" Mar 28 18:51:34 volumio volumio[1215]: info: Mar 28 18:51:34 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:34 volumio volumio[1215]: info: Mar 28 18:51:34 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:34 volumio volumio[1215]: info: Mar 28 18:51:34 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:34 volumio volumio[1215]: error: updateQueue error: null Mar 28 18:51:34 volumio volumio[1215]: error: updateQueue error: null Mar 28 18:51:34 volumio volumio[1215]: error: updateQueue error: null Mar 28 18:51:34 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand add "http://stream.webthings.hu:8000/fm95-x-128.mp3" Mar 28 18:51:34 volumio volumio[1215]: info: ------------------------------ 261ms Mar 28 18:51:34 volumio volumio[1215]: info: ------------------------------ 261ms Mar 28 18:51:34 volumio volumio[1215]: info: ------------------------------ 259ms Mar 28 18:51:34 volumio volumio[1215]: info: Mar 28 18:51:34 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:34 volumio volumio[1215]: info: sendMpdCommand add "http://stream.webthings.hu:8000/fm95-x-128.mp3" took 1 milliseconds Mar 28 18:51:34 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 28 18:51:34 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand play Mar 28 18:51:34 volumio volumio[1215]: info: Mar 28 18:51:34 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:34 volumio volumio[1215]: info: Mar 28 18:51:34 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:34 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:34 volumio volumio[1215]: info: ------------------------------ 4ms Mar 28 18:51:34 volumio volumio[1215]: info: sendMpdCommand play took 4 milliseconds Mar 28 18:51:34 volumio volumio[1215]: info: ------------------------------ 4ms Mar 28 18:51:34 volumio volumio[1215]: info: ------------------------------ 2ms Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 3 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 2 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 1 milliseconds Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:35 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:35 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: 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":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus stop Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: 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":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:51:35 volumio volumio[1215]: info: Received an update from plugin. extracting info from payload Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: 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":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:51:35 volumio volumio[1215]: info: Received an update from plugin. extracting info from payload Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 103ms Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 214ms Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 213ms Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:35 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:35 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 231ms Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 230 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 231ms Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 230 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 44ms Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 44 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 3ms Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 2 milliseconds Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:35 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces system playlist update Mar 28 18:51:35 volumio volumio[1215]: info: Ignoring MPD Status Update Mar 28 18:51:35 volumio volumio[1215]: info: Mar 28 18:51:35 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 80 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 79 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 78ms Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 106 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 105ms Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand status took 105 milliseconds Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:51:35 volumio volumio[1215]: info: Received an update from plugin. extracting info from payload Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:51:35 volumio volumio[1215]: info: Received an update from plugin. extracting info from payload Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1066,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:51:35 volumio volumio[1215]: info: Received an update from plugin. extracting info from payload Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1066,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:51:35 volumio volumio[1215]: info: Received an update from plugin. extracting info from payload Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 636ms Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 636ms Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 449ms Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 408ms Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 348 milliseconds Mar 28 18:51:35 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 349 milliseconds Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1066,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:51:35 volumio volumio[1215]: info: Received an update from plugin. extracting info from payload Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:35 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:35 volumio volumio[1215]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1066,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"David Guetta feat Nicki Minaj, Bebe Rexha ","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 18:51:35 volumio volumio[1215]: verbose: CURRENT POSITION 0 Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState stateService play Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::syncState currentStatus play Mar 28 18:51:35 volumio volumio[1215]: info: Received an update from plugin. extracting info from payload Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:35 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 592ms Mar 28 18:51:35 volumio volumio[1215]: info: ------------------------------ 591ms Mar 28 18:51:37 volumio volumio[1215]: info: CoreCommandRouter::volumioStop Mar 28 18:51:37 volumio volumio[1215]: info: CoreStateMachine::stop Mar 28 18:51:37 volumio volumio[1215]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 18:51:37 volumio volumio[1215]: info: CoreStateMachine::stPlaybackTimer Mar 28 18:51:37 volumio volumio[1215]: info: CoreStateMachine::updateTrackBlock Mar 28 18:51:37 volumio volumio[1215]: info: CorePlayQueue::getTrackBlock Mar 28 18:51:37 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:37 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:37 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 18:51:37 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:37 volumio volumio[1215]: info: CoreStateMachine::serviceStop Mar 28 18:51:37 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:37 volumio volumio[1215]: info: CoreCommandRouter::serviceStop Mar 28 18:51:37 volumio volumio[1215]: info: [1774720297974] ControllerWebradio::stop Mar 28 18:51:37 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 18:51:38 volumio volumio[1215]: info: Mar 28 18:51:38 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:38 volumio volumio[1215]: info: sendMpdCommand stop took 43 milliseconds Mar 28 18:51:38 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:38 volumio volumio[1215]: info: Mar 28 18:51:38 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:38 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:38 volumio volumio[1215]: info: Mar 28 18:51:38 volumio volumio[1215]: ---------------------------- MPD announces state update: player Mar 28 18:51:38 volumio volumio[1215]: info: ControllerMpd::getState Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand status Mar 28 18:51:38 volumio volumio[1215]: info: sendMpdCommand status took 4 milliseconds Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:38 volumio volumio[1215]: info: sendMpdCommand status took 11 milliseconds Mar 28 18:51:38 volumio volumio[1215]: info: sendMpdCommand status took 11 milliseconds Mar 28 18:51:38 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::parseState Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:38 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:38 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:38 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:38 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:38 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:38 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:38 volumio volumio[1215]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 18:51:38 volumio volumio[1215]: info: ------------------------------ 91ms Mar 28 18:51:38 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 28 18:51:38 volumio volumio[1215]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:38 volumio volumio[1215]: verbose: ControllerMpd::parseTrackInfo Mar 28 18:51:38 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:38 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:38 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:38 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:38 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:38 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:38 volumio volumio[1215]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 18:51:38 volumio volumio[1215]: info: ControllerMpd::pushState Mar 28 18:51:38 volumio volumio[1215]: info: CoreCommandRouter::servicePushState Mar 28 18:51:38 volumio volumio[1215]: info: CoreStateMachine::pushState Mar 28 18:51:38 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:38 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Mar 28 18:51:38 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Mar 28 18:51:38 volumio volumio[1215]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 28 18:51:38 volumio volumio[1215]: info: ------------------------------ 219ms Mar 28 18:51:38 volumio volumio[1215]: info: ------------------------------ 220ms Mar 28 18:51:43 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 18:51:43 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 18:51:53 volumio volumio[1215]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/bookworm/armhf Mar 28 18:51:53 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:53.897+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:51:57 volumio volumio[1215]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ytmusic/volumio/bookworm/armhf Mar 28 18:51:57 volumio volumio[1215]: info: Folder /tmp/plugins removed Mar 28 18:51:57 volumio volumio5-onboarding[1466]: time=2026-03-28T18:51:57.759+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:52:05 volumio volumio5-onboarding[1466]: time=2026-03-28T18:52:05.372+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:52:05 volumio volumio[1215]: info: Check plugin dependencies Mar 28 18:52:05 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 18:52:05 volumio volumio5-onboarding[1466]: time=2026-03-28T18:52:05.840+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:52:05 volumio volumio[1215]: info: Checking if plugin already exists Mar 28 18:52:05 volumio volumio5-onboarding[1466]: time=2026-03-28T18:52:05.861+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:52:05 volumio volumio[1215]: info: Rename folder Mar 28 18:52:05 volumio volumio[1215]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 18:52:05 volumio volumio5-onboarding[1466]: time=2026-03-28T18:52:05.960+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:52:05 volumio volumio[1215]: info: Move to category Mar 28 18:52:07 volumio volumio5-onboarding[1466]: time=2026-03-28T18:52:07.090+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:52:07 volumio volumio[1215]: info: Checking if install.sh is present Mar 28 18:52:07 volumio volumio[1215]: info: Check return the error Error: ENOENT: no such file or directory, stat '/data/plugins/music_service/ytmusic/install.sh' Mar 28 18:52:07 volumio volumio5-onboarding[1466]: time=2026-03-28T18:52:07.180+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 18:52:07 volumio volumio[1215]: info: An error occurred installing the plugin. Rolling back config Mar 28 18:52:07 volumio volumio[1215]: info: Plugin folders cleanup Mar 28 18:52:07 volumio volumio[1215]: info: Scanning into folder /volumio/app/plugins/ Mar 28 18:52:07 volumio volumio[1215]: info: Scanning category audio_interface Mar 28 18:52:07 volumio volumio[1215]: info: Scanning category miscellanea Mar 28 18:52:07 volumio volumio[1215]: info: Scanning category music_service Mar 28 18:52:07 volumio volumio[1215]: info: Scanning category plugins.json Mar 28 18:52:07 volumio volumio[1215]: info: Scanning category system_controller Mar 28 18:52:07 volumio volumio[1215]: info: Scanning category user_interface Mar 28 18:52:07 volumio volumio[1215]: info: Scanning into folder /data/plugins/ Mar 28 18:52:07 volumio volumio[1215]: info: Scanning category music_service Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/README.md Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/config.json Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/eslint.config.mjs Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/fixup.sh Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/gitignore Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/install.sh Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/package-lock.json Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/package.json Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/tsconfig.json Mar 28 18:52:07 volumio volumio[1215]: info: Removing /data/plugins//music_service/uninstall.sh Mar 28 18:52:07 volumio volumio[1215]: info: Plugin folders cleanup completed Mar 28 18:52:07 volumio volumio[1215]: info: Error: Error Mar 28 18:52:07 volumio volumio[1215]: info: Folder /tmp/plugins removed Mar 28 18:52:07 volumio volumio[1215]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 18:52:07 volumio volumio[1215]: info: Folder /data/temp removed Mar 28 18:52:20 volumio volumio[1215]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 18:52:20 volumio volumio[1215]: TypeError: Cannot read properties of undefined (reading 'plugin_type') Mar 28 18:52:20 volumio volumio[1215]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Mar 28 18:52:20 volumio volumio[1215]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29) Mar 28 18:52:20 volumio volumio[1215]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1353:45) Mar 28 18:52:20 volumio volumio[1215]: at Socket.emit (node:events:514:28) Mar 28 18:52:20 volumio volumio[1215]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 28 18:52:20 volumio volumio[1215]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 28 18:52:20 volumio volumio[1215]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 18:52:22 volumio avahi-daemon[764]: Record [_Volumio._tcp.local IN PTR Volumio._Volumio._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 18:52:22 volumio avahi-daemon[764]: Record [Volumio._Volumio._tcp.local IN TXT "volumioName=Volumio" "UUID=7dd345f1-9241-4b0b-a539-0edd50ba2020" ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 18:52:22 volumio avahi-daemon[764]: Record [Volumio._Volumio._tcp.local IN SRV 0 0 3000 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. Mar 28 18:52:22 volumio avahi-daemon[764]: Record [_device-info._tcp.local IN PTR VOLUMIO._device-info._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 18:52:22 volumio avahi-daemon[764]: Record [VOLUMIO._device-info._tcp.local IN TXT "model=AirPort" ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 18:52:22 volumio avahi-daemon[764]: Record [VOLUMIO._device-info._tcp.local IN SRV 0 0 0 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. Mar 28 18:52:22 volumio avahi-daemon[764]: Record [_smb._tcp.local IN PTR VOLUMIO._smb._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 18:52:22 volumio avahi-daemon[764]: Record [VOLUMIO._smb._tcp.local IN TXT ; ttl=4500] not fitting in legacy unicast packet, dropping. Mar 28 18:52:22 volumio avahi-daemon[764]: Record [VOLUMIO._smb._tcp.local IN SRV 0 0 445 volumio.local ; ttl=120] not fitting in legacy unicast packet, dropping. Mar 28 18:52:23 volumio sudo[1994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 18:51' Mar 28 18:52:23 volumio sudo[1994]: 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"