-- Logs begin at Thu 2025-05-01 04:26:00 JST, end at Tue 2025-05-06 18:14:11 JST. -- May 06 18:13:10 volumio volumio[1240]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState May 06 18:13:10 volumio volumio[1240]: info: Received Get System Info May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 18:13:10 volumio volumio[1240]: info: Discovery: Getting this device information May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 18:13:10 volumio volumio[1240]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 06 18:13:10 volumio volumio[1240]: info: Received Get System Info May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 18:13:10 volumio volumio[1240]: info: Discovery: Getting this device information May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState May 06 18:13:10 volumio volumio[1240]: info: Listing playlists May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:10 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:10 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 06 18:13:13 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput May 06 18:13:13 volumio volumio[1240]: info: MRS: Starting browser stream May 06 18:13:13 volumio volumio[1240]: info: MRS: Setting this device as Streaming Server May 06 18:13:13 volumio volumio[1240]: info: May 06 18:13:13 volumio volumio[1240]: [1746522793020] ---------------------------- MRS: Setting Streaming Server May 06 18:13:13 volumio volumio[1240]: info: Enabled audio output: browserPlayback May 06 18:13:13 volumio volumio[1240]: info: MRS: enable multiroom server output May 06 18:13:13 volumio volumio[1240]: info: MRS: Set multiroom target PCM to volumioMultiRoom May 06 18:13:13 volumio volumio[1240]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom May 06 18:13:13 volumio volumio[1240]: info: MRS: Set multiroom target PCM to volumioLocalPlayback May 06 18:13:13 volumio volumio[1240]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback May 06 18:13:13 volumio volumio[1240]: info: MRS: STARTING volumioStreaming May 06 18:13:13 volumio sudo[3708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 06 18:13:13 volumio sudo[3708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:13 volumio sudo[3708]: pam_unix(sudo:session): session closed for user root May 06 18:13:13 volumio sudo[3711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 06 18:13:13 volumio sudo[3711]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:13 volumio systemd[1]: Started VolumioStreamingService. May 06 18:13:13 volumio sudo[3711]: pam_unix(sudo:session): session closed for user root May 06 18:13:13 volumio volumio[1240]: info: MRS: volumioStreaming STARTED May 06 18:13:13 volumio sudo[3715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 06 18:13:13 volumio sudo[3715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:13 volumio sudo[3715]: pam_unix(sudo:session): session closed for user root May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 2787413 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:19 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:19 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:19 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:19 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 5013 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:20 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:20 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:20 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:20 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 6016 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:21 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:21 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:21 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:21 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7018 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:22 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:22 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:22 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:22 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 7999 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:23 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:23 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:23 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:23 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:24 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:24 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:24 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:24 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:24 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:24 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:24 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:24 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:24 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:24 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:24 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:24 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:24 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput May 06 18:13:34 volumio volumio[1240]: info: MRS: Starting browser stream May 06 18:13:34 volumio volumio[1240]: info: MRS: Setting this device as single May 06 18:13:34 volumio volumio[1240]: info: May 06 18:13:34 volumio volumio[1240]: [1746522814258] ---------------------------- Setting Multiroom Single May 06 18:13:34 volumio volumio[1240]: info: MRS: disable multiroom output May 06 18:13:34 volumio volumio[1240]: info: MRS: Set multiroom target PCM to volumioDiscard May 06 18:13:34 volumio volumio[1240]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard May 06 18:13:34 volumio volumio[1240]: ------------------------------------ BT MESSAGE: BT STATUS: paused May 06 18:13:34 volumio volumio[1240]: BT PLUGIN MESSAGE: Playing: false May 06 18:13:34 volumio volumio[1240]: BT PLUGIN MESSAGE: VOLATILE: bluetooth May 06 18:13:34 volumio volumio[1240]: BT PLUGIN MESSAGE: STATE:undefined May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:34 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:34 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:34 volumio volumio[1240]: verbose: UNSET VOLATILE: Service: bluetooth May 06 18:13:34 volumio volumio[1240]: info: Bluetooth Detach Command May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::resetVolumioState May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::getcurrentVolume May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::volumioRetrievevolume May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:34 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:34 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:34 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::volumioStop May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::stop May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:34 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:34 volumio volumio[1240]: verbose: STATE SERVICE {"status":"pause","service":"bluetooth","title":"Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - Kaori Muraji - プレーヤー | NHKラジオ らじる★らじる","artist":"Kaori Muraji","album":"ラプソディー・ジャパン","albumart":"/albumart?cacheid=550&web=Kaori%20Muraji/%E3%83%A9%E3%83%97%E3%82%BD%E3%83%87%E3%82%A3%E3%83%BC%E3%83%BB%E3%82%B8%E3%83%A3%E3%83%91%E3%83%B3/extralarge&path=&icon=&metadata=false","uri":"","trackType":"bt","seek":18093,"duration":9,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true,"stream":false,"codec":"","repeatMode":"all"} May 06 18:13:34 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::syncState stateService pause May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop May 06 18:13:34 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:34 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:34 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:34 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:34 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:34 volumio volumio[1240]: info: Bluetooth Audio successfully Disabled May 06 18:13:34 volumio volumio[1240]: info: Bluetooth Successfully Detached May 06 18:13:34 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 9002 May 06 18:13:34 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:34 volumio volumio[1240]: info: MRS: Set multiroom target PCM to volumioLocalPlayback May 06 18:13:34 volumio volumio[1240]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback May 06 18:13:35 volumio volumio[1240]: info: MRS: STOPPING volumioStreaming May 06 18:13:35 volumio sudo[3803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 06 18:13:35 volumio sudo[3803]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:35 volumio sudo[3806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream12.ts /tmp/hls/stream13.ts /tmp/hls/stream14.ts /tmp/hls/stream15.ts /tmp/hls/stream16.ts /tmp/hls/stream17.ts /tmp/hls/stream18.ts /tmp/hls/stream19.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream3.ts /tmp/hls/stream4.ts /tmp/hls/stream5.ts /tmp/hls/stream6.ts /tmp/hls/stream7.ts /tmp/hls/stream8.ts /tmp/hls/stream9.ts May 06 18:13:35 volumio sudo[3806]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:35 volumio systemd[1]: Stopping VolumioStreamingService... May 06 18:13:35 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM May 06 18:13:35 volumio sudo[3806]: pam_unix(sudo:session): session closed for user root May 06 18:13:35 volumio volumio[1240]: ------------------------------------ BT MESSAGE: BT STATUS: running May 06 18:13:35 volumio systemd[1]: volumioStreaming.service: Succeeded. May 06 18:13:35 volumio volumio[1240]: info: MRS: Removed streaming files May 06 18:13:35 volumio systemd[1]: Stopped VolumioStreamingService. May 06 18:13:35 volumio sudo[3803]: pam_unix(sudo:session): session closed for user root May 06 18:13:35 volumio volumio[1240]: info: MRS: volumioStreaming STOPPED May 06 18:13:37 volumio volumio[1240]: info: MRS: STARTING volumioStreaming May 06 18:13:37 volumio sudo[3809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 06 18:13:37 volumio sudo[3809]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:37 volumio sudo[3809]: pam_unix(sudo:session): session closed for user root May 06 18:13:37 volumio sudo[3812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 06 18:13:37 volumio sudo[3812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:37 volumio systemd[1]: Started VolumioStreamingService. May 06 18:13:37 volumio sudo[3812]: pam_unix(sudo:session): session closed for user root May 06 18:13:37 volumio volumio[1240]: info: MRS: volumioStreaming STARTED May 06 18:13:37 volumio sudo[3815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 06 18:13:37 volumio sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:37 volumio sudo[3815]: pam_unix(sudo:session): session closed for user root May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::play index undefined May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:38 volumio volumio[1240]: info: [1746522818808] ControllerUPNPBrowser::clearAddPlayTrack May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand stop took 1 milliseconds May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clear May 06 18:13:38 volumio volumio[1240]: info: May 06 18:13:38 volumio volumio[1240]: ---------------------------- MPD announces system playlist update May 06 18:13:38 volumio volumio[1240]: info: Ignoring MPD Status Update May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand clear took 1 milliseconds May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac" May 06 18:13:38 volumio volumio[1240]: error: updateQueue error: null May 06 18:13:38 volumio volumio[1240]: info: ------------------------------ 0ms May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac" May 06 18:13:38 volumio volumio[1240]: info: May 06 18:13:38 volumio volumio[1240]: ---------------------------- MPD announces system playlist update May 06 18:13:38 volumio volumio[1240]: info: Ignoring MPD Status Update May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand add "http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac" took 0 milliseconds May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService mpd May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand play May 06 18:13:38 volumio volumio[1240]: info: ------------------------------ 1ms May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand play took 1 milliseconds May 06 18:13:38 volumio volumio[1240]: info: May 06 18:13:38 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:38 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:38 volumio volumio[1240]: info: May 06 18:13:38 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds May 06 18:13:38 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:38 volumio volumio[1240]: info: May 06 18:13:38 volumio volumio[1240]: ---------------------------- MPD announces system playlist update May 06 18:13:38 volumio volumio[1240]: info: Ignoring MPD Status Update May 06 18:13:38 volumio volumio[1240]: info: May 06 18:13:38 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand status took 1 milliseconds May 06 18:13:38 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:38 volumio volumio[1240]: info: ControllerMpd::pushState May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:38 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2073,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler: Symphony #2 In C Minor, \"Resurrection\" - 5. Im Tempo Des Scherzos","artist":"Nathalie Stutzmann, Emiko Suga; Seiji Ozawa: Saito Kinen Orchestra, Sinyukai Choir","album":"Mahler: Symphony #2 [Disc 2]","uri":"http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac","trackType":"flac"} May 06 18:13:38 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop May 06 18:13:38 volumio volumio[1240]: info: ------------------------------ 7ms May 06 18:13:38 volumio volumio[1240]: info: ------------------------------ 3ms May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 2 milliseconds May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:38 volumio volumio[1240]: info: ControllerMpd::pushState May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:38 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2073,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler: Symphony #2 In C Minor, \"Resurrection\" - 5. Im Tempo Des Scherzos","artist":"Nathalie Stutzmann, Emiko Suga; Seiji Ozawa: Saito Kinen Orchestra, Sinyukai Choir","album":"Mahler: Symphony #2 [Disc 2]","uri":"http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac","trackType":"flac"} May 06 18:13:38 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play May 06 18:13:38 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:38 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:38 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:38 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:38 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:38 volumio volumio[1240]: info: ------------------------------ 16ms May 06 18:13:38 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 12 milliseconds May 06 18:13:38 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:38 volumio volumio[1240]: info: ControllerMpd::pushState May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:38 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":2073,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Mahler: Symphony #2 In C Minor, \"Resurrection\" - 5. Im Tempo Des Scherzos","artist":"Nathalie Stutzmann, Emiko Suga; Seiji Ozawa: Saito Kinen Orchestra, Sinyukai Choir","album":"Mahler: Symphony #2 [Disc 2]","uri":"http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac","trackType":"flac"} May 06 18:13:38 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play May 06 18:13:38 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:38 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:38 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:38 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:38 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:38 volumio volumio[1240]: info: ------------------------------ 21ms May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:38 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:38 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:38 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:38 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:38 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:39 volumio volumio[1240]: info: CoreCommandRouter::volumioPause May 06 18:13:39 volumio volumio[1240]: info: CoreStateMachine::pause May 06 18:13:39 volumio volumio[1240]: info: CoreStateMachine::stPlaybackTimer May 06 18:13:39 volumio volumio[1240]: info: CoreStateMachine::servicePause May 06 18:13:39 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:39 volumio volumio[1240]: info: CoreCommandRouter::servicePause May 06 18:13:39 volumio volumio[1240]: info: [1746522819082] ControllerUPNPBrowser::pause May 06 18:13:39 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand pause May 06 18:13:39 volumio volumio[1240]: info: May 06 18:13:39 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:39 volumio volumio[1240]: info: sendMpdCommand pause took 1 milliseconds May 06 18:13:39 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:39 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:39 volumio volumio[1240]: info: sendMpdCommand status took 0 milliseconds May 06 18:13:39 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:39 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:39 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 1 milliseconds May 06 18:13:39 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:39 volumio volumio[1240]: info: ControllerMpd::pushState May 06 18:13:39 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:39 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:39 volumio volumio[1240]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1715,"duration":2073,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Mahler: Symphony #2 In C Minor, \"Resurrection\" - 5. Im Tempo Des Scherzos","artist":"Nathalie Stutzmann, Emiko Suga; Seiji Ozawa: Saito Kinen Orchestra, Sinyukai Choir","album":"Mahler: Symphony #2 [Disc 2]","uri":"http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac","trackType":"flac"} May 06 18:13:39 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:39 volumio volumio[1240]: info: CoreStateMachine::syncState stateService pause May 06 18:13:39 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus pause May 06 18:13:39 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:39 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:39 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:39 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:39 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:39 volumio volumio[1240]: info: CoreStateMachine::stPlaybackTimer May 06 18:13:39 volumio volumio[1240]: info: ------------------------------ 5ms May 06 18:13:39 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:39 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:39 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:39 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:39 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:41 volumio volumio[1240]: info: Preload queue cleared May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioReplaceandPlayItems May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::ClearQueue May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::stop May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::stPlaybackTimer May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::serviceStop May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::serviceStop May 06 18:13:41 volumio volumio[1240]: info: [1746522821695] ControllerUPNPBrowser::stop May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::clearPlayQueue May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::saveQueue May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::addQueueItems May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::addQueueItems May 06 18:13:41 volumio volumio[1240]: info: Preload queue cleared May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P0R5887755 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P0R5887755 May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::saveQueue May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::play index 0 May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::addQueueItems May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::addQueueItems May 06 18:13:41 volumio volumio[1240]: info: Preload queue cleared May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P1R5887499 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P1R5887499 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P2R5887243 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P2R5887243 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P3R5886987 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P3R5886987 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P4R5886731 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P4R5886731 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P5R5886475 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P5R5886475 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P6R5886219 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P6R5886219 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P7R5885963 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P7R5885963 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P8R5885707 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P8R5885707 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P9R5885451 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P9R5885451 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P10R5885195 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P10R5885195 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P11R5884939 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P11R5884939 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P12R5884683 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P12R5884683 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P13R5884427 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P13R5884427 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P14R5884171 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P14R5884171 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P15R5883915 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P15R5883915 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P16R5883659 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P16R5883659 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P17R5883403 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P17R5883403 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P18R5883147 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P18R5883147 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P19R5882891 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P19R5882891 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P20R5882635 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P20R5882635 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P21R5882379 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P21R5882379 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P22R5882123 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P22R5882123 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P23R5881867 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P23R5881867 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P24R5881611 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P24R5881611 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P25R5881355 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P25R5881355 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P26R5881099 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P26R5881099 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P27R5880843 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P27R5880843 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P28R5880587 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P28R5880587 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P29R5880331 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P29R5880331 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P30R5880075 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P30R5880075 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P31R5879819 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P31R5879819 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P32R5879563 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P32R5879563 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P33R5879307 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P33R5879307 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P34R5879051 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P34R5879051 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P35R5878795 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P35R5878795 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P36R5878539 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P36R5878539 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P37R5878283 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P37R5878283 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P38R5878027 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P38R5878027 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P39R5877771 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P39R5877771 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P40R5877515 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P40R5877515 May 06 18:13:41 volumio volumio[1240]: info: Adding Item to queue: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P41R5877259 May 06 18:13:41 volumio volumio[1240]: info: Using cached record of: upnp/http://192.168.0.3:9050/dev0/srv1/control@0$1$45P41R5877259 May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::stop May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::saveQueue May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::play index undefined May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:41 volumio volumio[1240]: info: [1746522821708] ControllerUPNPBrowser::clearAddPlayTrack May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop May 06 18:13:41 volumio volumio[1240]: info: May 06 18:13:41 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand stop took 17 milliseconds May 06 18:13:41 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand stop took 4 milliseconds May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand clear May 06 18:13:41 volumio volumio[1240]: info: May 06 18:13:41 volumio volumio[1240]: ---------------------------- MPD announces system playlist update May 06 18:13:41 volumio volumio[1240]: info: Ignoring MPD Status Update May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand status took 0 milliseconds May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand clear took 0 milliseconds May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac" May 06 18:13:41 volumio volumio[1240]: error: updateQueue error: null May 06 18:13:41 volumio volumio[1240]: info: ------------------------------ 1ms May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 0 milliseconds May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:41 volumio volumio[1240]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 06 18:13:41 volumio volumio[1240]: info: ------------------------------ 2ms May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac" May 06 18:13:41 volumio volumio[1240]: info: May 06 18:13:41 volumio volumio[1240]: ---------------------------- MPD announces system playlist update May 06 18:13:41 volumio volumio[1240]: info: Ignoring MPD Status Update May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand add "http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac" took 1 milliseconds May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService mpd May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand play May 06 18:13:41 volumio volumio[1240]: info: ------------------------------ 0ms May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand play took 0 milliseconds May 06 18:13:41 volumio volumio[1240]: info: May 06 18:13:41 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:41 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:41 volumio volumio[1240]: info: May 06 18:13:41 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds May 06 18:13:41 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand status took 1 milliseconds May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 0 milliseconds May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:41 volumio volumio[1240]: info: ControllerMpd::pushState May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:41 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2073,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I5887755.flac","artist":null,"album":null,"uri":"http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac","trackType":"flac"} May 06 18:13:41 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus stop May 06 18:13:41 volumio volumio[1240]: info: ------------------------------ 4ms May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 1 milliseconds May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:41 volumio volumio[1240]: info: ControllerMpd::pushState May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:41 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2073,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I5887755.flac","artist":null,"album":null,"uri":"http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac","trackType":"flac"} May 06 18:13:41 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play May 06 18:13:41 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:41 volumio volumio[1240]: info: ------------------------------ 6ms May 06 18:13:41 volumio volumio[1240]: info: May 06 18:13:41 volumio volumio[1240]: ---------------------------- MPD announces system playlist update May 06 18:13:41 volumio volumio[1240]: info: Ignoring MPD Status Update May 06 18:13:41 volumio volumio[1240]: info: May 06 18:13:41 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:41 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:41 volumio volumio[1240]: info: ------------------------------ 1ms May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand status took 0 milliseconds May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:41 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 0 milliseconds May 06 18:13:41 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:41 volumio volumio[1240]: info: ControllerMpd::pushState May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:41 volumio volumio[1240]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":2073,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Mahler: Symphony #2 In C Minor, \"Resurrection\" - 5. Im Tempo Des Scherzos","artist":"Nathalie Stutzmann, Emiko Suga; Seiji Ozawa: Saito Kinen Orchestra, Sinyukai Choir","album":"Mahler: Symphony #2 [Disc 2]","uri":"http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac","trackType":"flac"} May 06 18:13:41 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::syncState stateService play May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus play May 06 18:13:41 volumio volumio[1240]: info: Received an update from plugin. extracting info from payload May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:41 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:41 volumio volumio[1240]: info: ------------------------------ 9ms May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:41 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:41 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:41 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:41 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:41 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:41 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:41 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:45 volumio volumio[1240]: info: CoreCommandRouter::volumioPause May 06 18:13:45 volumio volumio[1240]: info: CoreStateMachine::pause May 06 18:13:45 volumio volumio[1240]: info: CoreStateMachine::stPlaybackTimer May 06 18:13:45 volumio volumio[1240]: info: CoreStateMachine::servicePause May 06 18:13:45 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:45 volumio volumio[1240]: info: CoreCommandRouter::servicePause May 06 18:13:45 volumio volumio[1240]: info: [1746522825066] ControllerUPNPBrowser::pause May 06 18:13:45 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand pause May 06 18:13:45 volumio volumio[1240]: info: May 06 18:13:45 volumio volumio[1240]: ---------------------------- MPD announces state update: player May 06 18:13:45 volumio volumio[1240]: info: sendMpdCommand pause took 1 milliseconds May 06 18:13:45 volumio volumio[1240]: info: ControllerMpd::getState May 06 18:13:45 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status May 06 18:13:45 volumio volumio[1240]: info: sendMpdCommand status took 0 milliseconds May 06 18:13:45 volumio volumio[1240]: verbose: ControllerMpd::parseState May 06 18:13:45 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 18:13:45 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 0 milliseconds May 06 18:13:45 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo May 06 18:13:45 volumio volumio[1240]: info: ControllerMpd::pushState May 06 18:13:45 volumio volumio[1240]: info: CoreCommandRouter::servicePushState May 06 18:13:45 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 May 06 18:13:45 volumio volumio[1240]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4713,"duration":2073,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Mahler: Symphony #2 In C Minor, \"Resurrection\" - 5. Im Tempo Des Scherzos","artist":"Nathalie Stutzmann, Emiko Suga; Seiji Ozawa: Saito Kinen Orchestra, Sinyukai Choir","album":"Mahler: Symphony #2 [Disc 2]","uri":"http://192.168.0.3:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5887755.flac","trackType":"flac"} May 06 18:13:45 volumio volumio[1240]: verbose: CURRENT POSITION 0 May 06 18:13:45 volumio volumio[1240]: info: CoreStateMachine::syncState stateService pause May 06 18:13:45 volumio volumio[1240]: info: CoreStateMachine::syncState currentStatus pause May 06 18:13:45 volumio volumio[1240]: info: CoreStateMachine::pushState May 06 18:13:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 18:13:45 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState May 06 18:13:45 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device May 06 18:13:45 volumio volumio[1240]: info: MRS: Pushing multiroomSync output May 06 18:13:45 volumio volumio[1240]: info: CoreStateMachine::stPlaybackTimer May 06 18:13:45 volumio volumio[1240]: info: ------------------------------ 5ms May 06 18:13:45 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue May 06 18:13:45 volumio volumio[1240]: info: CoreStateMachine::getQueue May 06 18:13:45 volumio volumio[1240]: info: CorePlayQueue::getQueue May 06 18:13:45 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:45 volumio volumio[1240]: BT PLUGIN MESSAGE: Duration is 20010 May 06 18:13:48 volumio volumio[1240]: info: MRS: STARTING volumioStreaming May 06 18:13:48 volumio sudo[3839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 06 18:13:48 volumio sudo[3839]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:48 volumio sudo[3839]: pam_unix(sudo:session): session closed for user root May 06 18:13:48 volumio sudo[3842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 06 18:13:48 volumio sudo[3842]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:48 volumio systemd[1]: Stopping VolumioStreamingService... May 06 18:13:48 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM May 06 18:13:48 volumio volumio[1240]: ------------------------------------ BT MESSAGE: BT STATUS: running May 06 18:13:48 volumio systemd[1]: volumioStreaming.service: Succeeded. May 06 18:13:48 volumio systemd[1]: Stopped VolumioStreamingService. May 06 18:13:48 volumio volumio[1240]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 06 18:13:48 volumio systemd[1]: Started VolumioStreamingService. May 06 18:13:48 volumio sudo[3842]: pam_unix(sudo:session): session closed for user root May 06 18:13:48 volumio volumio[1240]: info: MRS: volumioStreaming STARTED May 06 18:13:48 volumio sudo[3845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 06 18:13:48 volumio sudo[3845]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 18:13:48 volumio sudo[3845]: pam_unix(sudo:session): session closed for user root May 06 18:13:49 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 18:13:49 volumio volumio[1240]: info: Preload queue cleared May 06 18:13:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 18:13:56 volumio volumio[1240]: info: Preload queue cleared May 06 18:14:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 18:14:01 volumio volumio[1240]: info: Preload queue cleared May 06 18:14:09 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 18:14:09 volumio volumio[1240]: info: Preload queue cleared May 06 18:14:11 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 06 18:14:11 volumio volumio[1240]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 18:14:11 volumio volumio[1240]: TypeError: Cannot read property 'length' of undefined May 06 18:14:11 volumio volumio[1240]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 06 18:14:11 volumio volumio[1240]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 06 18:14:11 volumio volumio[1240]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 06 18:14:11 volumio volumio[1240]: at Parser.emit (events.js:315:20) May 06 18:14:11 volumio volumio[1240]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 06 18:14:11 volumio volumio[1240]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 06 18:14:11 volumio volumio[1240]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 06 18:14:11 volumio volumio[1240]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 06 18:14:11 volumio volumio[1240]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 06 18:14:11 volumio volumio[1240]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 06 18:14:11 volumio volumio[1240]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 06 18:14:11 volumio volumio[1240]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 06 18:14:11 volumio volumio[1240]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 06 18:14:11 volumio volumio[1240]: at IncomingMessage.emit (events.js:327:22) May 06 18:14:11 volumio volumio[1240]: at endReadableNT (internal/streams/readable.js:1327:12) May 06 18:14:11 volumio volumio[1240]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 06 18:14:11 volumio volumio[1240]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 18:14:11 volumio sudo[3889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-06 18:13 May 06 18:14:11 volumio sudo[3889]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"