-- 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"