-- Logs begin at Tue 2025-10-28 07:16:31 CET, end at Wed 2025-10-29 07:13:23 CET. -- Oct 29 07:12:26 volumio volumio[988]: info: Preload queue cleared Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::ClearQueue Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::stop Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrackBlock Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 29 Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::serviceStop Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 29 Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::serviceStop Oct 29 07:12:26 volumio volumio[988]: info: [1761718346388] ControllerVolusonic::stop Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::stop Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::clearPlayQueue Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::saveQueue Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::addQueueItems Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::addQueueItems Oct 29 07:12:26 volumio volumio[988]: info: Preload queue cleared Oct 29 07:12:26 volumio volumio[988]: info: Adding Item to queue: volusonic/playlists/2nwL2rRzyG9SUeClHdquqT Oct 29 07:12:26 volumio volumio[988]: info: Using cached record of: volusonic/playlists/2nwL2rRzyG9SUeClHdquqT Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::saveQueue Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrackBlock Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioPlay Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::play index 0 Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::stop Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::play index undefined Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: [1761718346434] ControllerVolusonic::clearAddPlayTrack Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 07:12:26 volumio volumio[988]: info: [1761718346455] ControllerVolusonic: MPD player state update Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand stop took 66 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand stop took 23 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand clear Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:26 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 67 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 65 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 66 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand clear took 66 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 64 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 63 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 85ms Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 84ms Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:26 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:26 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:26 volumio volumio[988]: error: updateQueue error: null Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 28ms Oct 29 07:12:26 volumio volumio[988]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 49 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:26 volumio volumio[988]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 124ms Oct 29 07:12:26 volumio volumio[988]: error: updateQueue error: null Oct 29 07:12:26 volumio volumio[988]: error: updateQueue error: null Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:26 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 36ms Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 35ms Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand add "http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" took 8 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand play Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:26 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:26 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 9ms Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand play took 7 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 7ms Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 3ms Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 1 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:26 volumio volumio[988]: info: [1761718346594] ControllerVolusonic::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:12:26 volumio volumio[988]: verbose: CURRENT POSITION 0 Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:12:26 volumio volumio[988]: info: [1761718346719] ControllerVolusonic: MPD player state update Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 16 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 15 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 14 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 16 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 2 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: [1761718346742] ControllerVolusonic::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:12:26 volumio volumio[988]: verbose: CURRENT POSITION 0 Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Oct 29 07:12:26 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 36ms Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: Oct 29 07:12:26 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 6 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 55ms Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 42ms Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::volumioGetQueue Oct 29 07:12:26 volumio volumio[988]: info: CoreStateMachine::getQueue Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getQueue Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand status took 37 milliseconds Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 47ms Oct 29 07:12:26 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:12:26 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:26 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:26 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:26 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:26 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:26 volumio volumio[988]: info: ------------------------------ 48ms Oct 29 07:12:29 volumio volumio[988]: info: CoreCommandRouter::volumioPrevious Oct 29 07:12:29 volumio volumio[988]: info: CoreStateMachine::previous Oct 29 07:12:29 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:29 volumio volumio[988]: info: [1761718349050] ControllerVolusonic::seek to 0 Oct 29 07:12:29 volumio volumio[988]: info: ControllerMpd::seek Oct 29 07:12:29 volumio volumio[988]: error: null Oct 29 07:12:29 volumio volumio[988]: info: [1761718349062] ControllerVolusonic: MPD player state update Oct 29 07:12:29 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:29 volumio volumio[988]: info: Oct 29 07:12:29 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:29 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:29 volumio volumio[988]: info: Oct 29 07:12:29 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:29 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:29 volumio volumio[988]: info: Oct 29 07:12:29 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:29 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:29 volumio volumio[988]: info: sendMpdCommand status took 8 milliseconds Oct 29 07:12:29 volumio volumio[988]: info: sendMpdCommand status took 7 milliseconds Oct 29 07:12:29 volumio volumio[988]: info: sendMpdCommand status took 5 milliseconds Oct 29 07:12:29 volumio volumio[988]: info: sendMpdCommand status took 5 milliseconds Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:29 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:12:29 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:12:29 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:29 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:29 volumio volumio[988]: info: [1761718349077] ControllerVolusonic::pushState Oct 29 07:12:29 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:29 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:29 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:12:29 volumio volumio[988]: verbose: CURRENT POSITION 0 Oct 29 07:12:29 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:12:29 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Oct 29 07:12:29 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Oct 29 07:12:29 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:29 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:29 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:29 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:29 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:29 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:29 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:29 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:29 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:29 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:29 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:29 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:29 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:29 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:29 volumio volumio[988]: info: ------------------------------ 24ms Oct 29 07:12:29 volumio volumio[988]: info: ------------------------------ 22ms Oct 29 07:12:29 volumio volumio[988]: info: sendMpdCommand playlistinfo took 15 milliseconds Oct 29 07:12:29 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:29 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:29 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:29 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:29 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:29 volumio volumio[988]: info: ------------------------------ 23ms Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPrevious Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::previous Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::stop Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrackBlock Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::serviceStop Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::serviceStop Oct 29 07:12:30 volumio volumio[988]: info: [1761718350748] ControllerVolusonic::stop Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::stop Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 07:12:30 volumio volumio[988]: info: [1761718350781] ControllerVolusonic: MPD player state update Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:30 volumio volumio[988]: info: Oct 29 07:12:30 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand stop took 34 milliseconds Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:30 volumio volumio[988]: info: Oct 29 07:12:30 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:30 volumio volumio[988]: info: Oct 29 07:12:30 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand status took 6 milliseconds Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand status took 6 milliseconds Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand status took 6 milliseconds Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand status took 4 milliseconds Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand status took 6 milliseconds Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: [1761718350802] ControllerVolusonic::pushState Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:12:30 volumio volumio[988]: verbose: CURRENT POSITION 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::syncState stateService stop Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: No code Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:30 volumio volumio[988]: info: [1761718350830] ControllerVolusonic::pushState Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:12:30 volumio volumio[988]: verbose: CURRENT POSITION 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::syncState stateService stop Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: No code Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:30 volumio volumio[988]: info: ------------------------------ 67ms Oct 29 07:12:30 volumio volumio[988]: info: ------------------------------ 66ms Oct 29 07:12:30 volumio volumio[988]: info: sendMpdCommand playlistinfo took 57 milliseconds Oct 29 07:12:30 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:30 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:30 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:12:30 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:30 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:12:30 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:30 volumio volumio[988]: info: ------------------------------ 73ms Oct 29 07:12:30 volumio volumio[988]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 29 07:12:31 volumio volumio[988]: info: CoreStateMachine::play index undefined Oct 29 07:12:31 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:12:31 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:31 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer Oct 29 07:12:31 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:31 volumio volumio[988]: info: [1761718351249] ControllerVolusonic::clearAddPlayTrack Oct 29 07:12:31 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 07:12:31 volumio volumio[988]: info: sendMpdCommand stop took 1 milliseconds Oct 29 07:12:31 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand clear Oct 29 07:12:31 volumio volumio[988]: info: Oct 29 07:12:31 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:31 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:31 volumio volumio[988]: info: sendMpdCommand clear took 1 milliseconds Oct 29 07:12:31 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.131:4533/rest/stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" Oct 29 07:12:31 volumio volumio[988]: info: Oct 29 07:12:31 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:31 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:31 volumio volumio[988]: info: Oct 29 07:12:31 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:31 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:32 volumio volumio[988]: error: updateQueue error: null Oct 29 07:12:32 volumio volumio[988]: info: ------------------------------ 1145ms Oct 29 07:12:32 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.131:4533/rest/stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" Oct 29 07:12:32 volumio volumio[988]: error: updateQueue error: null Oct 29 07:12:32 volumio volumio[988]: error: updateQueue error: null Oct 29 07:12:32 volumio volumio[988]: info: ------------------------------ 1144ms Oct 29 07:12:32 volumio volumio[988]: info: ------------------------------ 1143ms Oct 29 07:12:32 volumio volumio[988]: info: Oct 29 07:12:32 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:32 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:32 volumio volumio[988]: info: sendMpdCommand add "http://192.168.1.131:4533/rest/stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" took 3 milliseconds Oct 29 07:12:32 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand play Oct 29 07:12:32 volumio volumio[988]: info: ------------------------------ 2ms Oct 29 07:12:32 volumio volumio[988]: info: Oct 29 07:12:32 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:32 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:32 volumio volumio[988]: info: Oct 29 07:12:32 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:32 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:32 volumio volumio[988]: info: sendMpdCommand play took 4 milliseconds Oct 29 07:12:32 volumio volumio[988]: info: ------------------------------ 3ms Oct 29 07:12:32 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:32 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:32 volumio volumio[988]: info: ------------------------------ 3ms Oct 29 07:12:32 volumio volumio[988]: info: sendMpdCommand status took 2 milliseconds Oct 29 07:12:32 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:32 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:32 volumio volumio[988]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 29 07:12:32 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:32 volumio volumio[988]: info: [1761718352408] ControllerVolusonic::pushState Oct 29 07:12:32 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:32 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:32 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:12:32 volumio volumio[988]: verbose: CURRENT POSITION 65 Oct 29 07:12:32 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:12:32 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:12:32 volumio volumio[988]: info: CoreCommandRouter::volumioPlay Oct 29 07:12:32 volumio volumio[988]: info: CoreStateMachine::play index undefined Oct 29 07:12:32 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:12:32 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: [1761718353741] ControllerVolusonic: MPD player state update Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 22 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 21 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 22 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 21 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 5 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: [1761718353772] ControllerVolusonic::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":36,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:12:33 volumio volumio[988]: verbose: CURRENT POSITION 65 Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Oct 29 07:12:33 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 40ms Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 39ms Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 38ms Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 15 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 15 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 25ms Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::volumioGetQueue Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::getQueue Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getQueue Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 46ms Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:33 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:33 volumio volumio[988]: info: [1761718353831] ControllerVolusonic: MPD player state update Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:33 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:12:33 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:12:33 volumio volumio[988]: info: Oct 29 07:12:33 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 7 milliseconds Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 19ms Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 17 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 17ms Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 16 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 15ms Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand status took 15 milliseconds Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 29 07:12:33 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:33 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: [1761718353861] ControllerVolusonic::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":537,"duration":36,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Uzi Doesn’t Read The Assignment","artist":"AJ DiSpirito","album":"Murder Drones Volume 1","uri":"http://192.168.1.131:4533/rest/stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:12:33 volumio volumio[988]: verbose: CURRENT POSITION 65 Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Oct 29 07:12:33 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:33 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:12:33 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:12:33 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:12:33 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 49ms Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 48ms Oct 29 07:12:33 volumio volumio[988]: info: ------------------------------ 46ms Oct 29 07:13:04 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:13:04 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:04 volumio volumio[988]: info: Prefetching next song Oct 29 07:13:04 volumio volumio[988]: info: [1761718384341] ControllerVolusonic::prefetch Oct 29 07:13:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.131:4533/rest/stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" Oct 29 07:13:04 volumio volumio[988]: info: sendMpdCommand add "http://192.168.1.131:4533/rest/stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" took 7 milliseconds Oct 29 07:13:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 29 07:13:04 volumio volumio[988]: info: Oct 29 07:13:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:04 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:04 volumio volumio[988]: info: sendMpdCommand consume 1 took 13 milliseconds Oct 29 07:13:04 volumio volumio[988]: info: Oct 29 07:13:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:04 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:04 volumio volumio[988]: info: Oct 29 07:13:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:04 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:04 volumio volumio[988]: info: ------------------------------ 13ms Oct 29 07:13:04 volumio volumio[988]: info: ------------------------------ 10ms Oct 29 07:13:04 volumio volumio[988]: info: ------------------------------ 9ms Oct 29 07:13:08 volumio volumio[988]: info: Oct 29 07:13:08 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:08 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:08 volumio volumio[988]: info: [1761718388533] ControllerVolusonic: MPD player state update Oct 29 07:13:08 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:08 volumio volumio[988]: info: Oct 29 07:13:08 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:08 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:08 volumio volumio[988]: info: Oct 29 07:13:08 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:08 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:08 volumio volumio[988]: info: Oct 29 07:13:08 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:08 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:08 volumio volumio[988]: info: Oct 29 07:13:08 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:08 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:08 volumio volumio[988]: info: Oct 29 07:13:08 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:08 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:08 volumio volumio[988]: info: sendMpdCommand status took 9 milliseconds Oct 29 07:13:08 volumio volumio[988]: info: ------------------------------ 9ms Oct 29 07:13:08 volumio volumio[988]: info: sendMpdCommand status took 8 milliseconds Oct 29 07:13:08 volumio volumio[988]: info: ------------------------------ 8ms Oct 29 07:13:08 volumio volumio[988]: info: sendMpdCommand status took 7 milliseconds Oct 29 07:13:08 volumio volumio[988]: info: ------------------------------ 6ms Oct 29 07:13:08 volumio volumio[988]: info: sendMpdCommand status took 6 milliseconds Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:08 volumio volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 29 07:13:08 volumio volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 29 07:13:08 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:13:08 volumio volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:08 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:08 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:13:08 volumio volumio[988]: info: [1761718388548] ControllerVolusonic::pushState Oct 29 07:13:08 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:08 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:13:08 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35577,"duration":36,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Uzi Doesn?t Read The Assignmen","artist":"AJ DiSpirito","album":"Murder Drones Volume 1","uri":"http://192.168.1.131:4533/rest/stream.view?id=pNp1Jq0i6NxxY24e8kXOiu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:13:08 volumio volumio[988]: verbose: CURRENT POSITION 65 Oct 29 07:13:08 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:13:08 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Oct 29 07:13:08 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Oct 29 07:13:08 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:08 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:13:08 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:13:08 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:08 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:08 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:13:08 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:08 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:08 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:08 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:13:08 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:08 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:08 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:08 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:13:08 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:08 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:08 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:08 volumio volumio[988]: info: CorePlayQueue::getTrack 65 Oct 29 07:13:08 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:08 volumio volumio[988]: info: ------------------------------ 28ms Oct 29 07:13:08 volumio volumio[988]: info: ------------------------------ 25ms Oct 29 07:13:08 volumio volumio[988]: info: ------------------------------ 24ms Oct 29 07:13:08 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer Oct 29 07:13:08 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: info: Oct 29 07:13:09 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:09 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:09 volumio volumio[988]: info: [1761718389233] ControllerVolusonic: MPD player state update Oct 29 07:13:09 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:09 volumio volumio[988]: info: Oct 29 07:13:09 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:09 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:09 volumio volumio[988]: info: Oct 29 07:13:09 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:09 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:09 volumio volumio[988]: info: Oct 29 07:13:09 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:09 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:09 volumio volumio[988]: info: sendMpdCommand status took 4 milliseconds Oct 29 07:13:09 volumio volumio[988]: info: ------------------------------ 4ms Oct 29 07:13:09 volumio volumio[988]: info: sendMpdCommand status took 3 milliseconds Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:09 volumio volumio[988]: info: Oct 29 07:13:09 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:09 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:09 volumio volumio[988]: info: Oct 29 07:13:09 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:09 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:09 volumio volumio[988]: info: ------------------------------ 14ms Oct 29 07:13:09 volumio volumio[988]: info: sendMpdCommand status took 14 milliseconds Oct 29 07:13:09 volumio volumio[988]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: info: [1761718389251] ControllerVolusonic::pushState Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:13:09 volumio volumio[988]: verbose: CURRENT POSITION 43 Oct 29 07:13:09 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:13:09 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Oct 29 07:13:09 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Oct 29 07:13:09 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:09 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:09 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:09 volumio volumio[988]: info: ------------------------------ 28ms Oct 29 07:13:09 volumio volumio[988]: info: ------------------------------ 15ms Oct 29 07:13:09 volumio volumio[988]: info: sendMpdCommand status took 15 milliseconds Oct 29 07:13:09 volumio volumio[988]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:09 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:09 volumio volumio[988]: info: ------------------------------ 30ms Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::volumioGetQueue Oct 29 07:13:09 volumio volumio[988]: info: CoreStateMachine::getQueue Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getQueue Oct 29 07:13:09 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:09 volumio volumio[988]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 29 07:13:09 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:09 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:09 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:09 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:09 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:09 volumio volumio[988]: info: ------------------------------ 46ms Oct 29 07:13:16 volumio volumio[988]: info: Preload queue cleared Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::ClearQueue Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrackBlock Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::serviceStop Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::serviceStop Oct 29 07:13:16 volumio volumio[988]: info: [1761718396408] ControllerVolusonic::stop Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::stop Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::clearPlayQueue Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::saveQueue Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::addQueueItems Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::addQueueItems Oct 29 07:13:16 volumio volumio[988]: info: Preload queue cleared Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/8cBYypmxzN26J9bpR8eynU Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/8cBYypmxzN26J9bpR8eynU in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/VPeOH98IOjWSVXaaWaH6kM Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/VPeOH98IOjWSVXaaWaH6kM in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/OzD1pImGNtmawp3Aen5Xg5 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/OzD1pImGNtmawp3Aen5Xg5 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/vlaHXGX4k46RR5iijVOjpE Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/vlaHXGX4k46RR5iijVOjpE in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/NiUTgqjhwcChxYzpI9YN00 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/NiUTgqjhwcChxYzpI9YN00 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/kVqa6RsyrVTKWoIImLvjUA Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/kVqa6RsyrVTKWoIImLvjUA in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/6HWSkNJwGVH2YKByQVpHQZ Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/6HWSkNJwGVH2YKByQVpHQZ in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/F84w38oZfc1bFWKhlAQE8G Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/F84w38oZfc1bFWKhlAQE8G in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/IeA7iOXHWH34SfJXb4oCXn Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/IeA7iOXHWH34SfJXb4oCXn in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/HjdQodSp0kFu23NCQAFkB8 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/HjdQodSp0kFu23NCQAFkB8 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/BgTLVFcKAQnro39ULY7mo1 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/BgTLVFcKAQnro39ULY7mo1 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/VWHdFaN1HKmTvr7Xfs4RGn Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/VWHdFaN1HKmTvr7Xfs4RGn in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/T1Eg4nTmExlUeVirNYxSCJ Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/T1Eg4nTmExlUeVirNYxSCJ in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/uGkMrdvooM4QGWGOwZCUqX Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/uGkMrdvooM4QGWGOwZCUqX in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/Xdi5YtyzymZYRiom7L9SM1 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/Xdi5YtyzymZYRiom7L9SM1 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/fXjzwxdscnkeIROd8R020W Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/fXjzwxdscnkeIROd8R020W in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/51DmJceXI3ELYRtbK1xs2z Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/51DmJceXI3ELYRtbK1xs2z in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/ewFPpbO0rX0WllO066Jf0f Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/ewFPpbO0rX0WllO066Jf0f in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/7AV1z51ulnDWXYI5dCsplP Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/7AV1z51ulnDWXYI5dCsplP in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/tg6C8CgJ7gGbgRcRzSDWcs Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/tg6C8CgJ7gGbgRcRzSDWcs in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/aCZKQ2mAySmMaIQ83Yc82d Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/aCZKQ2mAySmMaIQ83Yc82d in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/Bcel0whG6asr1bBBbfQPRZ Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/Bcel0whG6asr1bBBbfQPRZ in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/lE4v4HQSaubSqmp1EeAaxj Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/lE4v4HQSaubSqmp1EeAaxj in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/JDc8931ZgzOqVfIZiL7gJB Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/JDc8931ZgzOqVfIZiL7gJB in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/DlPfsjOTh3WdNQV6kND99a Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/DlPfsjOTh3WdNQV6kND99a in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/qSzy352EPkOsG0rgvF9tEw Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/qSzy352EPkOsG0rgvF9tEw in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/DZQ2UFuQ4rl5MofqcJBcyv Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/DZQ2UFuQ4rl5MofqcJBcyv in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/DgugyGsdbrxd7mcyTq4xnN Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/DgugyGsdbrxd7mcyTq4xnN in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/JbN0XKmvMwdzxWDud3a2Ul Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/JbN0XKmvMwdzxWDud3a2Ul in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/BfhMlOnviY4IWm3bfAlSsj Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/BfhMlOnviY4IWm3bfAlSsj in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/3bIxWx8oNh7BNoJEqwN3uW Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/3bIxWx8oNh7BNoJEqwN3uW in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/uHv3kwJn2GjTdD2CTQ8S9o Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/uHv3kwJn2GjTdD2CTQ8S9o in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/MreC2xwUIl5f1C4CbklBqN Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/MreC2xwUIl5f1C4CbklBqN in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/AqB2Kyf0rnQHrwwMSBrBQn Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/AqB2Kyf0rnQHrwwMSBrBQn in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/UjaFzjugPD7KWt2W1DL20h Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/UjaFzjugPD7KWt2W1DL20h in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/CMEW1CxqHJ7viGBzQlqhKm Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/CMEW1CxqHJ7viGBzQlqhKm in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/PX7HG4iRfBwkBerJCDf250 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/PX7HG4iRfBwkBerJCDf250 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/0h0bQeWwat0RrF8SoB5s0i Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/0h0bQeWwat0RrF8SoB5s0i in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/yEEo6cqLo1Ouepw9lhu1KO Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/yEEo6cqLo1Ouepw9lhu1KO in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/ajjU9we3fFtRgYHyFESnmG Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/ajjU9we3fFtRgYHyFESnmG in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/judo3eTdzMdck7cgRUDmb6 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/judo3eTdzMdck7cgRUDmb6 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/92BNr3JSouh3PnDoNEzk71 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/92BNr3JSouh3PnDoNEzk71 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/xvAq97dUNorwt26Wnnh3aF Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/xvAq97dUNorwt26Wnnh3aF in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/BVFN8Sg4PEu6hhAiVLJGfu Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/BVFN8Sg4PEu6hhAiVLJGfu in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/y6NeUROBylwb053ORvmnWJ Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/y6NeUROBylwb053ORvmnWJ in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/GGhxOfcDVhUikOhp833rrY Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/GGhxOfcDVhUikOhp833rrY in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/uR8KixYi6GFKd3hxHLllOL Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/uR8KixYi6GFKd3hxHLllOL in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/dUoMDv66ieXk7CHV3r82e2 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/dUoMDv66ieXk7CHV3r82e2 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/a7j80rGJG3Dahd1PyjBdAR Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/a7j80rGJG3Dahd1PyjBdAR in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/tQXMMpybQGfyvTH87AE9rY Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/tQXMMpybQGfyvTH87AE9rY in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/T3WKStlwTgFvC30A6rYIUz Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/T3WKStlwTgFvC30A6rYIUz in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/BLs970lhNZoRokgrjUia71 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/BLs970lhNZoRokgrjUia71 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/V3auKUzxcAYCnEXtkztwEZ Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/V3auKUzxcAYCnEXtkztwEZ in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/6STUvqinucbpiZLbCp67s0 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/6STUvqinucbpiZLbCp67s0 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/KQJVC35WLwCuAsARsHIVDC Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/KQJVC35WLwCuAsARsHIVDC in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/PKh5MwQESbzuJbv4gSkHfA Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/PKh5MwQESbzuJbv4gSkHfA in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/UmHvpVZYQi1iTCLesOhvWT Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/UmHvpVZYQi1iTCLesOhvWT in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/HMadUk48kC4dEoOzG78Xyd Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/HMadUk48kC4dEoOzG78Xyd in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/0688j9h8KnVWWRRKYPoqeF Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/0688j9h8KnVWWRRKYPoqeF in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/PDzULJ3berOZXveOLmpnTP Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/PDzULJ3berOZXveOLmpnTP in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/RP1S2nPnVC56yIZhziVKSh Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/RP1S2nPnVC56yIZhziVKSh in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/se0WZlyUr60uRaEnTSCgvK Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/se0WZlyUr60uRaEnTSCgvK in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/pLZK07lKSJow882b4wUy8G Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/pLZK07lKSJow882b4wUy8G in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/3RBThecpfhJiIvPBwsAmkW Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/3RBThecpfhJiIvPBwsAmkW in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/Pu6U8dz3WsAeiDZKZ6p8Tz Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/Pu6U8dz3WsAeiDZKZ6p8Tz in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/pNp1Jq0i6NxxY24e8kXOiu Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/pNp1Jq0i6NxxY24e8kXOiu in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/7podDaOF2I68jHlRqbnhU6 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/7podDaOF2I68jHlRqbnhU6 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/4AiFO56IrmuVehFAmdwJL6 Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/4AiFO56IrmuVehFAmdwJL6 in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: Adding Item to queue: volusonic/track/rhqbz10KwK4Bq9HqMRFVCw Oct 29 07:13:16 volumio volumio[988]: info: Exploding uri volusonic/track/rhqbz10KwK4Bq9HqMRFVCw in service volusonic Oct 29 07:13:16 volumio volumio[988]: info: [1761718396754] ControllerVolusonic: MPD player state update Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:16 volumio volumio[988]: info: Oct 29 07:13:16 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand stop took 347 milliseconds Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:16 volumio volumio[988]: info: Oct 29 07:13:16 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:16 volumio volumio[988]: info: Oct 29 07:13:16 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand status took 31 milliseconds Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand status took 31 milliseconds Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand status took 30 milliseconds Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand status took 29 milliseconds Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand status took 28 milliseconds Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 29 07:13:16 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 29 07:13:16 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:13:16 volumio volumio[988]: verbose: CURRENT POSITION 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::syncState stateService stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: No code Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: [1761718396807] ControllerVolusonic::pushState Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:13:16 volumio volumio[988]: verbose: CURRENT POSITION 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::syncState stateService stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: No code Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:13:16 volumio volumio[988]: verbose: CURRENT POSITION 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::syncState stateService stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: No code Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=BVFN8Sg4PEu6hhAiVLJGfu&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:13:16 volumio volumio[988]: verbose: CURRENT POSITION 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::syncState stateService stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: No code Oct 29 07:13:16 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:16 volumio volumio[988]: info: CorePlayQueue::getTrack 43 Oct 29 07:13:16 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:16 volumio volumio[988]: info: ------------------------------ 87ms Oct 29 07:13:16 volumio volumio[988]: info: ------------------------------ 86ms Oct 29 07:13:16 volumio volumio[988]: info: ------------------------------ 86ms Oct 29 07:13:16 volumio volumio[988]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 29 07:13:16 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/NiUTgqjhwcChxYzpI9YN00 from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/IeA7iOXHWH34SfJXb4oCXn from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/uGkMrdvooM4QGWGOwZCUqX from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/HjdQodSp0kFu23NCQAFkB8 from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/T1Eg4nTmExlUeVirNYxSCJ from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/7AV1z51ulnDWXYI5dCsplP from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/tg6C8CgJ7gGbgRcRzSDWcs from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/aCZKQ2mAySmMaIQ83Yc82d from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/uHv3kwJn2GjTdD2CTQ8S9o from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/CMEW1CxqHJ7viGBzQlqhKm from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/PX7HG4iRfBwkBerJCDf250 from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/MreC2xwUIl5f1C4CbklBqN from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/dUoMDv66ieXk7CHV3r82e2 from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/6STUvqinucbpiZLbCp67s0 from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/T3WKStlwTgFvC30A6rYIUz from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/KQJVC35WLwCuAsARsHIVDC from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/UmHvpVZYQi1iTCLesOhvWT from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/V3auKUzxcAYCnEXtkztwEZ from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/RP1S2nPnVC56yIZhziVKSh from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/se0WZlyUr60uRaEnTSCgvK from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/HMadUk48kC4dEoOzG78Xyd from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/rhqbz10KwK4Bq9HqMRFVCw from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue Oct 29 07:13:17 volumio volumio[988]: info: CorePlayQueue::saveQueue Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock Oct 29 07:13:17 volumio volumio[988]: info: CorePlayQueue::getTrackBlock Oct 29 07:13:17 volumio volumio[988]: info: CoreCommandRouter::volumioPlay Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::play index 68 Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::addQueueItems Oct 29 07:13:17 volumio volumio[988]: info: CorePlayQueue::addQueueItems Oct 29 07:13:17 volumio volumio[988]: info: Preload queue cleared Oct 29 07:13:17 volumio volumio[988]: info: Adding Item to queue: volusonic/track/S7N714FG3V972rHlDDptAc Oct 29 07:13:17 volumio volumio[988]: info: Exploding uri volusonic/track/S7N714FG3V972rHlDDptAc in service volusonic Oct 29 07:13:17 volumio volumio[988]: info: Adding Item to queue: volusonic/track/ZyS1DxpruLed5oA0dDDeC1 Oct 29 07:13:17 volumio volumio[988]: info: Exploding uri volusonic/track/ZyS1DxpruLed5oA0dDDeC1 in service volusonic Oct 29 07:13:17 volumio volumio[988]: info: Adding Item to queue: volusonic/track/9H7gCjmPGAxjtCvp1TMSB0 Oct 29 07:13:17 volumio volumio[988]: info: Exploding uri volusonic/track/9H7gCjmPGAxjtCvp1TMSB0 in service volusonic Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::stop Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::play index undefined Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:13:17 volumio volumio[988]: info: CorePlayQueue::getTrack 68 Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/ZyS1DxpruLed5oA0dDDeC1 from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: error: Commandrouter: Cannot explode uri volusonic/track/9H7gCjmPGAxjtCvp1TMSB0 from service volusonic: Error: explodeUri volusonic/track Oct 29 07:13:17 volumio volumio[988]: info: CoreCommandRouter::volumioPushQueue Oct 29 07:13:17 volumio volumio[988]: info: CorePlayQueue::saveQueue Oct 29 07:13:17 volumio volumio[988]: info: CoreStateMachine::updateTrackBlock Oct 29 07:13:17 volumio volumio[988]: info: CorePlayQueue::getTrackBlock Oct 29 07:13:19 volumio volumio[988]: info: CoreCommandRouter::volumioPlay Oct 29 07:13:19 volumio volumio[988]: info: CoreStateMachine::play index undefined Oct 29 07:13:19 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 29 07:13:19 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:19 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer Oct 29 07:13:19 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:19 volumio volumio[988]: info: [1761718399811] ControllerVolusonic::clearAddPlayTrack Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand stop Oct 29 07:13:19 volumio volumio[988]: info: sendMpdCommand stop took 1 milliseconds Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand clear Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:19 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:19 volumio volumio[988]: info: sendMpdCommand clear took 2 milliseconds Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:19 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:19 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:19 volumio volumio[988]: error: updateQueue error: null Oct 29 07:13:19 volumio volumio[988]: info: ------------------------------ 4ms Oct 29 07:13:19 volumio volumio[988]: error: updateQueue error: null Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" Oct 29 07:13:19 volumio volumio[988]: info: ------------------------------ 54ms Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:19 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:19 volumio volumio[988]: info: sendMpdCommand add "http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic" took 3 milliseconds Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand play Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:19 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces system playlist update Oct 29 07:13:19 volumio volumio[988]: info: Ignoring MPD Status Update Oct 29 07:13:19 volumio volumio[988]: info: ------------------------------ 4ms Oct 29 07:13:19 volumio volumio[988]: info: sendMpdCommand play took 9 milliseconds Oct 29 07:13:19 volumio volumio[988]: info: ------------------------------ 9ms Oct 29 07:13:19 volumio volumio[988]: info: ------------------------------ 8ms Oct 29 07:13:19 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:19 volumio volumio[988]: info: sendMpdCommand status took 0 milliseconds Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:19 volumio volumio[988]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:19 volumio volumio[988]: info: [1761718399887] ControllerVolusonic::pushState Oct 29 07:13:19 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:19 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:19 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:13:19 volumio volumio[988]: verbose: CURRENT POSITION 0 Oct 29 07:13:19 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:13:19 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Oct 29 07:13:19 volumio volumio[988]: info: [1761718399993] ControllerVolusonic: MPD player state update Oct 29 07:13:19 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:19 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:19 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:19 volumio volumio[988]: info: Oct 29 07:13:19 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:19 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:20 volumio volumio[988]: info: Oct 29 07:13:20 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:20 volumio volumio[988]: info: Oct 29 07:13:20 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:20 volumio volumio[988]: info: Oct 29 07:13:20 volumio volumio[988]: ---------------------------- MPD announces state update: player Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand status took 27 milliseconds Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand status took 26 milliseconds Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::getState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand status took 3 milliseconds Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand status took 5 milliseconds Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand status took 4 milliseconds Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseState Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: [1761718400028] ControllerVolusonic::pushState Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.131:4533/rest/stream.view?id=8cBYypmxzN26J9bpR8eynU&format=raw&u=Pietro&t=05ae6e24c2dc49f78a891d14c566ffc0&s=p6gmb&v=1.10.2&f=json&c=volusonic","trackType":""} Oct 29 07:13:20 volumio volumio[988]: verbose: CURRENT POSITION 0 Oct 29 07:13:20 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Oct 29 07:13:20 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Oct 29 07:13:20 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Oct 29 07:13:20 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:20 volumio volumio[988]: info: CoreStateMachine::pushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:20 volumio volumio[988]: info: ------------------------------ 45ms Oct 29 07:13:20 volumio volumio[988]: info: ------------------------------ 44ms Oct 29 07:13:20 volumio volumio[988]: info: ------------------------------ 43ms Oct 29 07:13:20 volumio volumio[988]: info: ------------------------------ 21ms Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 29 07:13:20 volumio volumio[988]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 29 07:13:20 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:20 volumio volumio[988]: info: ControllerMpd::pushState Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::servicePushState Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Oct 29 07:13:20 volumio volumio[988]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Oct 29 07:13:20 volumio volumio[988]: info: ------------------------------ 24ms Oct 29 07:13:20 volumio volumio[988]: info: ------------------------------ 22ms Oct 29 07:13:20 volumio volumio[988]: info: CoreCommandRouter::volumioGetQueue Oct 29 07:13:20 volumio volumio[988]: info: CoreStateMachine::getQueue Oct 29 07:13:20 volumio volumio[988]: info: CorePlayQueue::getQueue Oct 29 07:13:22 volumio volumio[988]: info: Adding volusonic/track/rhqbz10KwK4Bq9HqMRFVCw to favourites with specific volusonic method Oct 29 07:13:22 volumio volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 07:13:22 volumio volumio[988]: TypeError: Cannot read property 'then' of undefined Oct 29 07:13:22 volumio volumio[988]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:679:20) Oct 29 07:13:22 volumio volumio[988]: at Socket.emit (events.js:315:20) Oct 29 07:13:22 volumio volumio[988]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 29 07:13:22 volumio volumio[988]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 29 07:13:22 volumio volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 07:13:23 volumio sudo[18581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-29 07:12 Oct 29 07:13:23 volumio sudo[18581]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"