Aug 31 17:42:02 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:06 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:10 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:14 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand update Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: info: Aug 31 17:42:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand update took 53 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: info: Aug 31 17:42:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: info: Aug 31 17:42:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 12 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 24 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 24 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 23 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 22 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 22 milliseconds Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:18 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:18 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:18 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:18 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:18 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:18 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:18 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:18 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:18 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:18 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:18 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:18 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:18 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:18 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:18 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:18 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:18 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:18.729+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:18 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:18.729+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:18 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:18.730+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:18 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:18.731+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:18 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:18.731+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:18 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:18.732+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:18 volumio volumio[1289]: info: ------------------------------ 461ms Aug 31 17:42:18 volumio volumio[1289]: info: ------------------------------ 460ms Aug 31 17:42:18 volumio volumio[1289]: info: ------------------------------ 475ms Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: info: Aug 31 17:42:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: info: Aug 31 17:42:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: info: Aug 31 17:42:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 133 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 132 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 28 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 28 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 27 milliseconds Aug 31 17:42:18 volumio volumio[1289]: info: sendMpdCommand status took 27 milliseconds Aug 31 17:42:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:19 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:19 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:19 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:19.337+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:19.338+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:19.339+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:19.339+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:19.375+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:19.375+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:19 volumio volumio[1289]: info: ------------------------------ 628ms Aug 31 17:42:19 volumio volumio[1289]: info: ------------------------------ 523ms Aug 31 17:42:19 volumio volumio[1289]: info: ------------------------------ 550ms Aug 31 17:42:22 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:26 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:28 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand update Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:28 volumio volumio[1289]: info: Aug 31 17:42:28 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:28 volumio volumio[1289]: info: sendMpdCommand update took 51 milliseconds Aug 31 17:42:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:28 volumio volumio[1289]: info: Aug 31 17:42:28 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:28 volumio volumio[1289]: info: Aug 31 17:42:28 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:28 volumio volumio[1289]: info: Aug 31 17:42:28 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:28 volumio volumio[1289]: info: sendMpdCommand status took 71 milliseconds Aug 31 17:42:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:28 volumio volumio[1289]: info: sendMpdCommand status took 36 milliseconds Aug 31 17:42:28 volumio volumio[1289]: info: sendMpdCommand status took 37 milliseconds Aug 31 17:42:28 volumio volumio[1289]: info: sendMpdCommand status took 39 milliseconds Aug 31 17:42:28 volumio volumio[1289]: info: sendMpdCommand status took 39 milliseconds Aug 31 17:42:28 volumio volumio[1289]: info: sendMpdCommand status took 38 milliseconds Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:28 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:28 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:28 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:28 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:29 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.251+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.251+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.265+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.266+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.278+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.278+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:29 volumio volumio[1289]: info: ------------------------------ 699ms Aug 31 17:42:29 volumio volumio[1289]: info: ------------------------------ 633ms Aug 31 17:42:29 volumio volumio[1289]: info: ------------------------------ 633ms Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:29 volumio volumio[1289]: info: Aug 31 17:42:29 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:29 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:29 volumio volumio[1289]: info: Aug 31 17:42:29 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:29 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:29 volumio volumio[1289]: info: sendMpdCommand status took 642 milliseconds Aug 31 17:42:29 volumio volumio[1289]: info: sendMpdCommand status took 613 milliseconds Aug 31 17:42:29 volumio volumio[1289]: info: sendMpdCommand status took 13 milliseconds Aug 31 17:42:29 volumio volumio[1289]: info: sendMpdCommand status took 12 milliseconds Aug 31 17:42:29 volumio volumio[1289]: info: sendMpdCommand status took 11 milliseconds Aug 31 17:42:29 volumio volumio[1289]: info: sendMpdCommand status took 9 milliseconds Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:29 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:29 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:29 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.731+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.731+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.737+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.737+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.740+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:29.741+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:29 volumio volumio[1289]: info: ------------------------------ 1055ms Aug 31 17:42:29 volumio volumio[1289]: info: ------------------------------ 421ms Aug 31 17:42:29 volumio volumio[1289]: info: ------------------------------ 419ms Aug 31 17:42:30 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:34 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:37 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 17:42:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand update Aug 31 17:42:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:37 volumio volumio[1289]: info: Aug 31 17:42:37 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:37 volumio volumio[1289]: info: sendMpdCommand update took 11 milliseconds Aug 31 17:42:37 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:37 volumio volumio[1289]: info: Aug 31 17:42:37 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:37 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:37 volumio volumio[1289]: info: Aug 31 17:42:37 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:37 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:37 volumio volumio[1289]: info: sendMpdCommand status took 30 milliseconds Aug 31 17:42:37 volumio volumio[1289]: info: sendMpdCommand status took 30 milliseconds Aug 31 17:42:37 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:38 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:38 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:38 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:38.157+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:38 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:38.157+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:38 volumio volumio[1289]: info: ------------------------------ 267ms Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:38 volumio volumio[1289]: info: Aug 31 17:42:38 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 329 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 301 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 301 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 301 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:38 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:38 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:38 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:38 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:38 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:38 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:38.635+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:38 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:38.636+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:38 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:38.637+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:38 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:38.638+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:38 volumio volumio[1289]: info: ------------------------------ 791ms Aug 31 17:42:38 volumio volumio[1289]: info: ------------------------------ 764ms Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:38 volumio volumio[1289]: info: Aug 31 17:42:38 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:38 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:38 volumio volumio[1289]: info: Aug 31 17:42:38 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:38 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 516 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 512 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 51 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 46 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 46 milliseconds Aug 31 17:42:38 volumio volumio[1289]: info: sendMpdCommand status took 45 milliseconds Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:38 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:39 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:39 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:39 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:39 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:39 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:39 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:39 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:39 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:39 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:39 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:39 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:39 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:39 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:39 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:39 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:39 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:39 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:39 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:39 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:39 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:39 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:39 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:39 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:39 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:39 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:39.058+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:39 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:39.059+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:39 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:39.065+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:39 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:39.066+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:39 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:39.067+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:39 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:39.068+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:39 volumio volumio[1289]: info: ------------------------------ 882ms Aug 31 17:42:39 volumio volumio[1289]: info: ------------------------------ 417ms Aug 31 17:42:39 volumio volumio[1289]: info: ------------------------------ 411ms Aug 31 17:42:42 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand update Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: info: Aug 31 17:42:45 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:45 volumio volumio[1289]: info: sendMpdCommand update took 11 milliseconds Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: info: Aug 31 17:42:45 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: info: Aug 31 17:42:45 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: info: sendMpdCommand status took 32 milliseconds Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:45 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: info: Aug 31 17:42:45 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:45 volumio volumio[1289]: info: sendMpdCommand status took 300 milliseconds Aug 31 17:42:45 volumio volumio[1289]: info: sendMpdCommand status took 294 milliseconds Aug 31 17:42:45 volumio volumio[1289]: info: sendMpdCommand status took 295 milliseconds Aug 31 17:42:45 volumio volumio[1289]: info: sendMpdCommand status took 302 milliseconds Aug 31 17:42:45 volumio volumio[1289]: info: sendMpdCommand status took 302 milliseconds Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:45 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:45 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:45 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:45 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:45 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:45 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:45 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:45.727+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:45 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:45.727+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:45 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:45 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:45 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:45 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:45 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:45 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:45 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:45 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:45 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:45 volumio volumio[1289]: info: ------------------------------ 660ms Aug 31 17:42:45 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:45.825+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:45 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:45.826+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:45 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:45.827+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:45 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:45.827+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:45 volumio volumio[1289]: info: ------------------------------ 693ms Aug 31 17:42:45 volumio volumio[1289]: info: ------------------------------ 712ms Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: info: Aug 31 17:42:45 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:45 volumio volumio[1289]: info: Aug 31 17:42:45 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:42:45 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:42:45 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:42:46 volumio volumio[1289]: info: sendMpdCommand status took 553 milliseconds Aug 31 17:42:46 volumio volumio[1289]: info: sendMpdCommand status took 542 milliseconds Aug 31 17:42:46 volumio volumio[1289]: info: sendMpdCommand status took 133 milliseconds Aug 31 17:42:46 volumio volumio[1289]: info: sendMpdCommand status took 132 milliseconds Aug 31 17:42:46 volumio volumio[1289]: info: sendMpdCommand status took 114 milliseconds Aug 31 17:42:46 volumio volumio[1289]: info: sendMpdCommand status took 133 milliseconds Aug 31 17:42:46 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:46 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:46 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:46 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:46 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:46 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:46 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:46 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:42:46 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:42:46 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:46 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:46 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:46 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:46 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:46 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:46 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:42:46 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:42:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:42:46 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:42:46 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:42:46 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:46.550+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:46 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:46.550+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:46 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:46.554+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:46 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:46.555+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:46 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:46.565+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:42:46 volumio volumio5-onboarding[1601]: time=2026-08-31T17:42:46.565+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:42:46 volumio volumio[1289]: info: ------------------------------ 1267ms Aug 31 17:42:46 volumio volumio[1289]: info: ------------------------------ 846ms Aug 31 17:42:46 volumio volumio[1289]: info: ------------------------------ 827ms Aug 31 17:42:46 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:42:50 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:43:00 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:00.672+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.269502ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 17:43:00 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:00.750+09:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s Aug 31 17:43:00 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:00.921+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=171.671388ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.221+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://www.googleapis.com duration=470.91464ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.239+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=489.237261ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.312+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://functions.volumio.cloud duration=562.26557ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.314+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://functions.volumio.cloud duration=563.287113ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.320+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=569.649407ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.339+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=589.093753ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.359+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=http://pushupdates.volumio.org duration=608.402085ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.364+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://securetoken.googleapis.com duration=614.596814ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.556+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=http://plugins.volumio.org duration=805.49499ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.570+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://google.com duration=820.566099ms Aug 31 17:43:01 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:01.605+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=https://database.volumio.cloud duration=854.536594ms Aug 31 17:43:01 volumio sudo[2515]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 17:43:01 volumio sudo[2515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:43:01 volumio sudo[2513]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 17:43:01 volumio sudo[2515]: pam_unix(sudo:session): session closed for user root Aug 31 17:43:01 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:43:01 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root Aug 31 17:43:02 volumio volumio[1289]: verbose: New Socket.io Connection to 192.168.219.130 from 192.168.219.47 UA: Mozilla/5.0 (Linux; Android 16; SM-S901N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 31 17:43:02 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:02.134+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" latency=331.030683ms timeout=10s endpoint=http://cddb.volumio.org duration=1.384163837s Aug 31 17:43:02 volumio sudo[2537]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 17:43:02 volumio sudo[2537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:43:02 volumio sudo[2535]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 17:43:02 volumio sudo[2535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 17:43:02 volumio sudo[2535]: pam_unix(sudo:session): session closed for user root Aug 31 17:43:02 volumio sudo[2537]: pam_unix(sudo:session): session closed for user root Aug 31 17:43:02 volumio volumio[1289]: verbose: New Socket.io Connection to 192.168.219.130 from 192.168.219.47 UA: Mozilla/5.0 (Linux; Android 16; SM-S901N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Aug 31 17:43:03 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 17:43:03 volumio volumio[1289]: info: Received Get System Info Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:43:03 volumio volumio[1289]: info: Discovery: Getting this device information Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Aug 31 17:43:03 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Aug 31 17:43:03 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:03 volumio volumio[1289]: info: Listing playlists Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 17:43:03 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 17:43:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 17:43:04 volumio volumio[1289]: info: Received Get System Info Aug 31 17:43:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:43:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:43:04 volumio volumio[1289]: info: Discovery: Getting this device information Aug 31 17:43:04 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Aug 31 17:43:04 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:43:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 17:43:08 volumio volumio[1289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 17:43:08 volumio volumio[1289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 17:43:08 volumio volumio[1289]: info: Retrieving Cloud Streaming UI Aug 31 17:43:08 volumio volumio[1289]: info: Getting Tidal Cloud Configuration Aug 31 17:43:08 volumio volumio[1289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 17:43:08 volumio volumio[1289]: info: Getting Qobuz Cloud Configuration Aug 31 17:43:08 volumio volumio[1289]: info: Asking plugin for UI Config Aug 31 17:43:08 volumio volumio[1289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 17:43:08 volumio volumio[1289]: info: Getting Spotify Cloud Configuration Aug 31 17:43:08 volumio volumio[1289]: info: Asking plugin for UI Config Aug 31 17:43:08 volumio volumio[1289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 17:43:08 volumio volumio[1289]: info: Saving Spotify Acccount Aug 31 17:43:08 volumio volumio[1289]: info: Got Tidal Cloud Configuration Aug 31 17:43:08 volumio volumio[1289]: info: Got it Aug 31 17:43:08 volumio volumio[1289]: info: Got it Aug 31 17:43:08 volumio volumio[1289]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 17:43:08 volumio volumio[1289]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 17:43:08 volumio volumio[1289]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:43:09 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 17:43:12 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 17:43:13 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:43:17 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:43:18 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand update Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:18 volumio volumio[1289]: info: Aug 31 17:43:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:18 volumio volumio[1289]: info: sendMpdCommand update took 11 milliseconds Aug 31 17:43:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:18 volumio volumio[1289]: info: Aug 31 17:43:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:18 volumio volumio[1289]: info: Aug 31 17:43:18 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:18 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:18 volumio volumio[1289]: info: sendMpdCommand status took 36 milliseconds Aug 31 17:43:18 volumio volumio[1289]: info: sendMpdCommand status took 35 milliseconds Aug 31 17:43:18 volumio volumio[1289]: info: sendMpdCommand status took 35 milliseconds Aug 31 17:43:18 volumio volumio[1289]: info: sendMpdCommand status took 34 milliseconds Aug 31 17:43:18 volumio volumio[1289]: info: sendMpdCommand status took 3 milliseconds Aug 31 17:43:18 volumio volumio[1289]: info: sendMpdCommand status took 2 milliseconds Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:18 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:18 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:18 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:18 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:18 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:18 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:18 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.234+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.235+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.235+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.235+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.235+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.235+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:19 volumio volumio[1289]: info: ------------------------------ 741ms Aug 31 17:43:19 volumio volumio[1289]: info: ------------------------------ 739ms Aug 31 17:43:19 volumio volumio[1289]: info: ------------------------------ 708ms Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:19 volumio volumio[1289]: info: Aug 31 17:43:19 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:19 volumio volumio[1289]: info: Aug 31 17:43:19 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:19 volumio volumio[1289]: info: Aug 31 17:43:19 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:19 volumio volumio[1289]: info: sendMpdCommand status took 190 milliseconds Aug 31 17:43:19 volumio volumio[1289]: info: sendMpdCommand status took 144 milliseconds Aug 31 17:43:19 volumio volumio[1289]: info: sendMpdCommand status took 143 milliseconds Aug 31 17:43:19 volumio volumio[1289]: info: sendMpdCommand status took 142 milliseconds Aug 31 17:43:19 volumio volumio[1289]: info: sendMpdCommand status took 109 milliseconds Aug 31 17:43:19 volumio volumio[1289]: info: sendMpdCommand status took 107 milliseconds Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:19 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:19 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:19 volumio volumio[1289]: info: Command Router : Notfying DB Updatefalse Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:19 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:19 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:19 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:19 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:19 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.945+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.946+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.947+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.947+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.955+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:19 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:19.956+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:20 volumio volumio[1289]: info: ------------------------------ 727ms Aug 31 17:43:20 volumio volumio[1289]: info: ------------------------------ 681ms Aug 31 17:43:20 volumio volumio[1289]: info: ------------------------------ 646ms Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand rescan Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:20 volumio volumio[1289]: info: Aug 31 17:43:20 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:20 volumio volumio[1289]: info: sendMpdCommand rescan took 44 milliseconds Aug 31 17:43:20 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:20 volumio volumio[1289]: info: Aug 31 17:43:20 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:20 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:20 volumio volumio[1289]: info: Aug 31 17:43:20 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:20 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:20 volumio volumio[1289]: info: sendMpdCommand status took 45 milliseconds Aug 31 17:43:20 volumio volumio[1289]: info: sendMpdCommand status took 44 milliseconds Aug 31 17:43:20 volumio volumio[1289]: info: sendMpdCommand status took 43 milliseconds Aug 31 17:43:20 volumio volumio[1289]: info: sendMpdCommand status took 31 milliseconds Aug 31 17:43:20 volumio volumio[1289]: info: sendMpdCommand status took 30 milliseconds Aug 31 17:43:20 volumio volumio[1289]: info: sendMpdCommand status took 2 milliseconds Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:20 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:20 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:20 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:20 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:20 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:20 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:20 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:20 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:20 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:20 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:20 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:20 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:20 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:20 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:20 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:20 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:20 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:20 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:20 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:20 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:20 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:20.888+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:20 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:20.889+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:20 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:20.889+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:20 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:20.889+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:20 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:20.919+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:20 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:20.919+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:20 volumio volumio[1289]: info: ------------------------------ 479ms Aug 31 17:43:21 volumio volumio[1289]: info: ------------------------------ 478ms Aug 31 17:43:21 volumio volumio[1289]: info: ------------------------------ 517ms Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 17:43:21 volumio volumio[1289]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 17:43:21 volumio volumio[1289]: info: Received Get System Version Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 17:43:21 volumio volumio[1289]: info: Received Get System Info Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 17:43:21 volumio volumio[1289]: info: Discovery: Getting this device information Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Aug 31 17:43:21 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 31 17:43:21 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand rescan Aug 31 17:43:21 volumio volumio[1289]: info: sendMpdCommand rescan took 49 milliseconds Aug 31 17:43:21 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:43:25 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:43:28 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 17:43:28 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 17:43:28 volumio volumio[1289]: info: MPD Database updated - AlbumList cache refreshed Aug 31 17:43:28 volumio volumio[1289]: info: Aug 31 17:43:28 volumio volumio[1289]: ---------------------------- MPD announces state update: database Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: Aug 31 17:43:28 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: MPD Database updated - AlbumList cache refreshed Aug 31 17:43:28 volumio volumio[1289]: info: Aug 31 17:43:28 volumio volumio[1289]: ---------------------------- MPD announces state update: database Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: Aug 31 17:43:28 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: MPD Database updated - AlbumList cache refreshed Aug 31 17:43:28 volumio volumio[1289]: info: Aug 31 17:43:28 volumio volumio[1289]: ---------------------------- MPD announces state update: database Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: Aug 31 17:43:28 volumio volumio[1289]: ---------------------------- MPD announces state update: update Aug 31 17:43:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: ControllerMpd::getState Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 101 milliseconds Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 103 milliseconds Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 106 milliseconds Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:28 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:28 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:28 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:28 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:28 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:28 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:28 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:28 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:28 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:28 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:28 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:28 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:28 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:28 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:28 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:28 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:28 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:28.797+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:28 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:28.797+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:28 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:28.821+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:28 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:28.821+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:28 volumio volumio[1289]: info: ------------------------------ 462ms Aug 31 17:43:28 volumio volumio[1289]: info: ------------------------------ 462ms Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 492 milliseconds Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 465 milliseconds Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 464 milliseconds Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 463 milliseconds Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 394 milliseconds Aug 31 17:43:28 volumio volumio[1289]: info: sendMpdCommand status took 394 milliseconds Aug 31 17:43:28 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:29 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:29 volumio volumio[1289]: info: Command Router : Notfying DB Updatetrue Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::Close All Modals sent Aug 31 17:43:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:29 volumio volumio[1289]: verbose: ControllerMpd::parseState Aug 31 17:43:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:29 volumio volumio[1289]: info: ControllerMpd::pushState Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Aug 31 17:43:29 volumio volumio[1289]: info: CoreStateMachine::pushState Aug 31 17:43:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Aug 31 17:43:29 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Aug 31 17:43:29 volumio volumio[1289]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 31 17:43:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:29.185+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:29.186+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:29.187+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:29.188+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:29.205+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:29.206+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:29.207+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" state=STATUS_STOPPED positionMs=0 volume= Aug 31 17:43:29 volumio volumio5-onboarding[1601]: time=2026-08-31T17:43:29.207+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.219.47:55876,00:00:00:00:00:00%01 @ 0x2c01500" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" title="SmoothJazz.com Global" Aug 31 17:43:29 volumio volumio[1289]: info: ------------------------------ 864ms Aug 31 17:43:29 volumio volumio[1289]: info: ------------------------------ 864ms Aug 31 17:43:29 volumio volumio[1289]: info: ------------------------------ 834ms Aug 31 17:43:29 volumio volumio[1289]: info: ------------------------------ 835ms Aug 31 17:43:29 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 17:43:29 volumio volumio[1289]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 17:43:29 volumio volumio[1289]: Error: getaddrinfo -3007 Aug 31 17:43:29 volumio volumio[1289]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Aug 31 17:43:29 volumio volumio[1289]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Aug 31 17:43:29 volumio volumio[1289]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Aug 31 17:43:29 volumio volumio[1289]: code: -3007, Aug 31 17:43:29 volumio volumio[1289]: errno: -3007, Aug 31 17:43:29 volumio volumio[1289]: syscall: 'getaddrinfo' Aug 31 17:43:29 volumio volumio[1289]: } Aug 31 17:43:29 volumio volumio[1289]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 17:43:32 volumio sudo[2638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 17:42' Aug 31 17:43:32 volumio sudo[2638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"