Mar 24 17:15:01 volumio2 volumio[1224]: info: Preload queue cleared Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::ClearQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::stop Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::updateTrackBlock Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrackBlock Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::stPlaybackTimer Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::serviceStop Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::serviceStop Mar 24 17:15:01 volumio2 volumio[1224]: info: [1774340101730] ControllerUPNPBrowser::stop Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::clearPlayQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::saveQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::addQueueItems Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::addQueueItems Mar 24 17:15:01 volumio2 volumio[1224]: info: Preload queue cleared Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9103 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9103 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.731+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.732+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::saveQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::updateTrackBlock Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrackBlock Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPlay Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::play index 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::addQueueItems Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::addQueueItems Mar 24 17:15:01 volumio2 volumio[1224]: info: Preload queue cleared Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i4940 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i4940 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i12463 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i12463 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i12453 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i12453 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9618 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9618 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i11994 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i11994 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10045 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10045 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i7124 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i7124 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10848 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10848 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9821 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9821 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i4942 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i4942 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9628 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9628 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9515 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9515 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9820 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9820 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10161 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10161 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10157 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10157 Mar 24 17:15:01 volumio2 volumio[1224]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10159 Mar 24 17:15:01 volumio2 volumio[1224]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i10159 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::stop Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::saveQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::play index undefined Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::updateTrackBlock Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrackBlock Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::startPlaybackTimer Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: [1774340101750] ControllerUPNPBrowser::clearAddPlayTrack Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand stop took 27 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand stop took 7 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 2 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand clear took 2 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 1 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 2 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac" Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: 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 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService stop Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus stop Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: No code Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: 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 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService stop Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus stop Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: No code Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.772+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.772+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.773+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.773+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.775+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.776+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.776+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.776+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.776+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.776+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.778+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.778+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 35ms Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 34ms Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: error: updateQueue error: null Mar 24 17:15:01 volumio2 volumio[1224]: error: updateQueue error: null Mar 24 17:15:01 volumio2 volumio[1224]: error: updateQueue error: null Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 39ms Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 38 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac" Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 5ms Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 5ms Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 43ms Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand add "http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac" took 10 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand play Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 6ms Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand play took 5 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 2ms Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 1ms Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 3 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 8 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 7 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 6 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 5 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 2 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus stop Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 13ms Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:15:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:15:01 volumio2 volumio[1224]: info: Mar 24 17:15:01 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 8ms Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 7 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 4ms Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"640 Kbps","isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:15:01 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"624 Kbps","isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:15:01 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"681 Kbps","isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:15:01 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"546 Kbps","isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:15:01 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"573 Kbps","isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:15:01 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.860+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.860+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.862+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.862+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.866+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.867+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.869+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.869+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.870+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.871+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.872+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.872+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.874+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.874+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.875+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.876+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.876+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.877+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.879+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.879+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 85ms Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 84ms Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 84ms Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 82ms Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 82ms Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 74 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 74ms Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand status took 73 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 70 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:15:01 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.917+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.918+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.918+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.919+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 93ms Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 49 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 49 milliseconds Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:15:01 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:15:01 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":65,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"607 Kbps","isStreaming":false,"title":"No Doubt","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac","trackType":"flac"} Mar 24 17:15:01 volumio2 volumio[1224]: verbose: CURRENT POSITION 0 Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:15:01 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.973+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.974+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.974+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.975+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.975+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.976+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.976+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:15:01.976+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/01*20No*20Doubt.flac title="No Doubt" Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 153ms Mar 24 17:15:01 volumio2 volumio[1224]: info: ------------------------------ 152ms Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:15:01 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 0 Mar 24 17:16:01 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:01 volumio2 volumio[1224]: info: Prefetching next song Mar 24 17:16:01 volumio2 volumio[1224]: info: Doing Prefetch in UPNP Mar 24 17:16:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac" Mar 24 17:16:01 volumio2 volumio[1224]: info: Mar 24 17:16:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:16:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:16:01 volumio2 volumio[1224]: info: sendMpdCommand add "http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac" took 2 milliseconds Mar 24 17:16:01 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 24 17:16:01 volumio2 volumio[1224]: info: Mar 24 17:16:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:16:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:16:01 volumio2 volumio[1224]: info: Mar 24 17:16:01 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:16:01 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:16:01 volumio2 volumio[1224]: info: ------------------------------ 2ms Mar 24 17:16:01 volumio2 volumio[1224]: info: sendMpdCommand consume 1 took 2 milliseconds Mar 24 17:16:01 volumio2 volumio[1224]: info: ------------------------------ 2ms Mar 24 17:16:01 volumio2 volumio[1224]: info: ------------------------------ 1ms Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::startPlaybackTimer Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: Mar 24 17:16:06 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:16:06 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:16:06 volumio2 volumio[1224]: info: Mar 24 17:16:06 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:16:06 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:16:06 volumio2 volumio[1224]: info: Mar 24 17:16:06 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:16:06 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:16:06 volumio2 volumio[1224]: info: Mar 24 17:16:06 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:16:06 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:16:06 volumio2 volumio[1224]: info: Mar 24 17:16:06 volumio2 volumio[1224]: ---------------------------- MPD announces system playlist update Mar 24 17:16:06 volumio2 volumio[1224]: info: Ignoring MPD Status Update Mar 24 17:16:06 volumio2 volumio[1224]: info: Mar 24 17:16:06 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:16:06 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:16:06 volumio2 volumio[1224]: info: ------------------------------ 3ms Mar 24 17:16:06 volumio2 volumio[1224]: info: sendMpdCommand status took 2 milliseconds Mar 24 17:16:06 volumio2 volumio[1224]: info: ------------------------------ 2ms Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:16:06 volumio2 volumio[1224]: info: sendMpdCommand status took 2 milliseconds Mar 24 17:16:06 volumio2 volumio[1224]: info: ------------------------------ 3ms Mar 24 17:16:06 volumio2 volumio[1224]: info: sendMpdCommand status took 2 milliseconds Mar 24 17:16:06 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:16:06 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"299 Kbps","isStreaming":false,"title":"Departure","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac","trackType":"flac"} Mar 24 17:16:06 volumio2 volumio[1224]: verbose: CURRENT POSITION 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:16:06 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.336+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=137 volume=100 Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.336+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=137 volume=100 Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.337+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.337+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:06 volumio2 volumio[1224]: info: ------------------------------ 19ms Mar 24 17:16:06 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 24 17:16:06 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 17 milliseconds Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:16:06 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:16:06 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"299 Kbps","isStreaming":false,"title":"Departure","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac","trackType":"flac"} Mar 24 17:16:06 volumio2 volumio[1224]: verbose: CURRENT POSITION 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:16:06 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:06 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"299 Kbps","isStreaming":false,"title":"Departure","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac","trackType":"flac"} Mar 24 17:16:06 volumio2 volumio[1224]: verbose: CURRENT POSITION 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService play Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus play Mar 24 17:16:06 volumio2 volumio[1224]: info: Received an update from plugin. extracting info from payload Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.351+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=137 volume=100 Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.352+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.353+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=137 volume=100 Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.353+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.354+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=137 volume=100 Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.354+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.355+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=137 volume=100 Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.355+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:06 volumio2 volumio[1224]: info: ------------------------------ 40ms Mar 24 17:16:06 volumio2 volumio[1224]: info: ------------------------------ 40ms Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.807+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PLAYING positionMs=387 volume=100 Mar 24 17:16:06 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:06.807+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:06 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPause Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::pause Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::stPlaybackTimer Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::servicePause Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::servicePause Mar 24 17:16:09 volumio2 volumio[1224]: info: [1774340169045] ControllerUPNPBrowser::pause Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand pause Mar 24 17:16:09 volumio2 volumio[1224]: info: sendMpdCommand pause took 0 milliseconds Mar 24 17:16:09 volumio2 volumio[1224]: info: Mar 24 17:16:09 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:16:09 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:16:09 volumio2 volumio[1224]: info: Mar 24 17:16:09 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:16:09 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:16:09 volumio2 volumio[1224]: info: Mar 24 17:16:09 volumio2 volumio[1224]: ---------------------------- MPD announces state update: player Mar 24 17:16:09 volumio2 volumio[1224]: info: ControllerMpd::getState Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:16:09 volumio2 volumio[1224]: info: sendMpdCommand status took 2 milliseconds Mar 24 17:16:09 volumio2 volumio[1224]: info: sendMpdCommand status took 2 milliseconds Mar 24 17:16:09 volumio2 volumio[1224]: info: sendMpdCommand status took 1 milliseconds Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::parseState Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:16:09 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 17:16:09 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 17:16:09 volumio2 volumio[1224]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:16:09 volumio2 volumio[1224]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:16:09 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:09 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2636,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"344 Kbps","isStreaming":false,"title":"Departure","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac","trackType":"flac"} Mar 24 17:16:09 volumio2 volumio[1224]: verbose: CURRENT POSITION 1 Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService pause Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus pause Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::stPlaybackTimer Mar 24 17:16:09 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:09 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2636,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"344 Kbps","isStreaming":false,"title":"Departure","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac","trackType":"flac"} Mar 24 17:16:09 volumio2 volumio[1224]: verbose: CURRENT POSITION 1 Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService pause Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus pause Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::stPlaybackTimer Mar 24 17:16:09 volumio2 volumio[1224]: info: ControllerMpd::pushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::servicePushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:09 volumio2 volumio[1224]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":2636,"duration":283,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"344 Kbps","isStreaming":false,"title":"Departure","artist":"佐藤 直紀","album":"Good Luck!!","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac","trackType":"flac"} Mar 24 17:16:09 volumio2 volumio[1224]: verbose: CURRENT POSITION 1 Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::syncState stateService pause Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::syncState currentStatus pause Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::pushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::volumioPushState Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::stPlaybackTimer Mar 24 17:16:09 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:09.059+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PAUSED positionMs=2641 volume=100 Mar 24 17:16:09 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:09.059+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:09 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:09.060+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PAUSED positionMs=2641 volume=100 Mar 24 17:16:09 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:09.060+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:09 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:09.061+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" state=STATUS_PAUSED positionMs=2641 volume=100 Mar 24 17:16:09 volumio2 volumio5-onboarding[1586]: time=2026-03-24T17:16:09.062+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.47:49283,00:00:00:00:00:00%01 @ 0x149e210" id=http://192.168.0.222:9790/minimserver/*/Multimedia/C*20D*20*e3*83*a9*e3*82*a4*e3*83*96*e3*83*a9*e3*83*aa*e3*83*bc/*e3*83*9d*e3*83*94*e3*83*a5*e3*83*a9*e3*83*bc*e3*80*80*e3*81*9d*e3*81*ae*e4*bb*96/GOOD*20LUCK*20!!/02*20Departure.flac title=Departure Mar 24 17:16:09 volumio2 volumio[1224]: info: ------------------------------ 22ms Mar 24 17:16:09 volumio2 volumio[1224]: info: ------------------------------ 22ms Mar 24 17:16:09 volumio2 volumio[1224]: info: ------------------------------ 21ms Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CoreStateMachine::getQueue Mar 24 17:16:09 volumio2 volumio[1224]: info: CorePlayQueue::getQueue Mar 24 17:16:13 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetState Mar 24 17:16:13 volumio2 volumio[1224]: info: CorePlayQueue::getTrack 1 Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 17:16:17 volumio2 volumio[1224]: info: Retrieving Cloud Streaming UI Mar 24 17:16:17 volumio2 volumio[1224]: info: Getting Tidal Cloud Configuration Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 17:16:17 volumio2 volumio[1224]: info: Getting Qobuz Cloud Configuration Mar 24 17:16:17 volumio2 volumio[1224]: info: Asking plugin for UI Config Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 17:16:17 volumio2 volumio[1224]: info: Getting Spotify Cloud Configuration Mar 24 17:16:17 volumio2 volumio[1224]: info: Asking plugin for UI Config Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 17:16:17 volumio2 volumio[1224]: info: Saving Spotify Acccount Mar 24 17:16:17 volumio2 volumio[1224]: info: Got Tidal Cloud Configuration Mar 24 17:16:17 volumio2 volumio[1224]: info: Got it Mar 24 17:16:17 volumio2 volumio[1224]: info: Got it Mar 24 17:16:17 volumio2 volumio[1224]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 17:16:17 volumio2 volumio[1224]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetBrowseSources Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetBrowseSources Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::volumioGetBrowseSources Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 17:16:17 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 24 17:16:21 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 17:16:22 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 24 17:16:22 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 24 17:16:24 volumio2 volumio[1224]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L HS-210N.local. -m SMB3_11 Mar 24 17:16:24 volumio2 volumio[1224]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L HS-210.local. -m SMB3_11 Mar 24 17:16:25 volumio2 volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 17:16:27 volumio2 volumio[1224]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 17:16:27 volumio2 volumio[1224]: Error: dns service error: unknown Mar 24 17:16:27 volumio2 volumio[1224]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Mar 24 17:16:27 volumio2 volumio[1224]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Mar 24 17:16:27 volumio2 volumio[1224]: errorCode: -65537 Mar 24 17:16:27 volumio2 volumio[1224]: } Mar 24 17:16:27 volumio2 volumio[1224]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 17:16:28 volumio2 sudo[13311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 17:15' Mar 24 17:16:28 volumio2 sudo[13311]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"