-- Logs begin at Tue 2024-07-09 17:29:06 AEST, end at Thu 2024-07-11 10:09:13 AEST. -- Jul 11 10:08:01 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:08:01 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:01 volumio volumio[1712]: info: Listing playlists Jul 11 10:08:01 volumio volumio[1712]: info: Listing playlists Jul 11 10:08:11 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:08:11 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:21 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:08:21 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:21 volumio volumio[1712]: info: Listing playlists Jul 11 10:08:21 volumio volumio[1712]: info: Listing playlists Jul 11 10:08:31 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:08:31 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:39 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Jul 11 10:08:39 volumio volumio[1712]: info: Preload queue cleared Jul 11 10:08:39 volumio volumio[1712]: info: Preloading song: volusonic/track/6f50fbef326241c984182cc09512096c Jul 11 10:08:39 volumio volumio[1712]: info: Preloading song: volusonic/track/04b0d0d07100cd809fd84089ad03205f Jul 11 10:08:39 volumio volumio[1712]: info: Preloading song: volusonic/track/9aea76252e628f17dd318c8542fa86f5 Jul 11 10:08:39 volumio volumio[1712]: info: Preloading song: volusonic/track/0fd40f21454d2f8f6c7014c3cda9bcc2 Jul 11 10:08:39 volumio volumio[1712]: info: Preloading song: volusonic/track/6ee43f68bfcda7f211a7fdf8e33778fa Jul 11 10:08:39 volumio volumio[1712]: info: Preloading song: volusonic/track/6dbe02ebfabb4b1313c91b224a3d3445 Jul 11 10:08:40 volumio volumio[1712]: info: Exploding uri volusonic/track/6f50fbef326241c984182cc09512096c in service volusonic Jul 11 10:08:40 volumio volumio[1712]: info: No valid Plugin REST Endpoint Jul 11 10:08:40 volumio volumio[1712]: info: Exploding uri volusonic/track/04b0d0d07100cd809fd84089ad03205f in service volusonic Jul 11 10:08:40 volumio volumio[1712]: info: Exploding uri volusonic/track/9aea76252e628f17dd318c8542fa86f5 in service volusonic Jul 11 10:08:40 volumio volumio[1712]: info: Exploding uri volusonic/track/0fd40f21454d2f8f6c7014c3cda9bcc2 in service volusonic Jul 11 10:08:40 volumio volumio[1712]: info: Exploding uri volusonic/track/6ee43f68bfcda7f211a7fdf8e33778fa in service volusonic Jul 11 10:08:40 volumio volumio[1712]: info: Exploding uri volusonic/track/6dbe02ebfabb4b1313c91b224a3d3445 in service volusonic Jul 11 10:08:40 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:40 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:40 volumio volumio[1712]: info: Prefetching next song Jul 11 10:08:40 volumio volumio[1712]: info: [1720656520345] ControllerVolusonic::prefetch Jul 11 10:08:40 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.10.237:4533/rest/stream.view?id=8bcf1b52d7dde07ca749e526842e879f&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic" Jul 11 10:08:40 volumio volumio[1712]: info: Jul 11 10:08:40 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:40 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:40 volumio volumio[1712]: info: sendMpdCommand add "http://192.168.10.237:4533/rest/stream.view?id=8bcf1b52d7dde07ca749e526842e879f&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic" took 2 milliseconds Jul 11 10:08:40 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 11 10:08:40 volumio volumio[1712]: info: Jul 11 10:08:40 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:40 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:40 volumio volumio[1712]: info: Jul 11 10:08:40 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:40 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:40 volumio volumio[1712]: info: ------------------------------ 4ms Jul 11 10:08:40 volumio volumio[1712]: info: sendMpdCommand consume 1 took 3 milliseconds Jul 11 10:08:40 volumio volumio[1712]: info: ------------------------------ 2ms Jul 11 10:08:40 volumio volumio[1712]: info: ------------------------------ 2ms Jul 11 10:08:41 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:08:41 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:41 volumio volumio[1712]: info: Listing playlists Jul 11 10:08:41 volumio volumio[1712]: info: Listing playlists Jul 11 10:08:44 volumio volumio[1712]: info: CoreStateMachine::startPlaybackTimer Jul 11 10:08:44 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: info: Jul 11 10:08:45 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:45 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:45 volumio volumio[1712]: info: [1720656525357] ControllerVolusonic: MPD player state update Jul 11 10:08:45 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:45 volumio volumio[1712]: info: Jul 11 10:08:45 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:45 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:45 volumio volumio[1712]: info: Jul 11 10:08:45 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:45 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:45 volumio volumio[1712]: info: Jul 11 10:08:45 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:45 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:45 volumio volumio[1712]: info: Jul 11 10:08:45 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:45 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:45 volumio volumio[1712]: info: Jul 11 10:08:45 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:45 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:45 volumio volumio[1712]: info: sendMpdCommand status took 3 milliseconds Jul 11 10:08:45 volumio volumio[1712]: info: ------------------------------ 3ms Jul 11 10:08:45 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:45 volumio volumio[1712]: info: ------------------------------ 4ms Jul 11 10:08:45 volumio volumio[1712]: info: sendMpdCommand status took 4 milliseconds Jul 11 10:08:45 volumio volumio[1712]: info: ------------------------------ 3ms Jul 11 10:08:45 volumio volumio[1712]: info: sendMpdCommand status took 3 milliseconds Jul 11 10:08:45 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:45 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: info: [1720656525366] ControllerVolusonic::pushState Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":124,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1257 Kbps","isStreaming":false,"title":"Justin","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=8bcf1b52d7dde07ca749e526842e879f&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:45 volumio volumio[1712]: verbose: CURRENT POSITION 7 Jul 11 10:08:45 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:45 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus play Jul 11 10:08:45 volumio volumio[1712]: info: Received an update from plugin. extracting info from payload Jul 11 10:08:45 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:45 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:45 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:45 volumio volumio[1712]: info: ------------------------------ 15ms Jul 11 10:08:45 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 11 10:08:45 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:45 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:45 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:45 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:45 volumio volumio[1712]: info: ------------------------------ 16ms Jul 11 10:08:45 volumio volumio[1712]: info: ------------------------------ 15ms Jul 11 10:08:45 volumio volumio[1712]: info: [LastFM] Current track has sufficient metadata: title (Justin) and artist (Vince Staples) passed on explicitly Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::volumioGetQueue Jul 11 10:08:45 volumio volumio[1712]: info: CoreStateMachine::getQueue Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getQueue Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:45 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:45 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:45 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:45 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:47 volumio volumio[1712]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Justin","url":"https://www.last.fm/music/Vince+Staples/_/Justin","duration":"124000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"63571","playcount":"212806","artist":{"name":"Vince Staples","mbid":"78e854b8-9713-4ff2-9218-6b3784893bff","url":"https://www.last.fm/music/Vince+Staples"},"album":{"artist":"Vince Staples","title":"Dark Times","url":"https://www.last.fm/music/Vince+Staples/Dark+Times","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/e47493caab2fee23efbb60413d654cee.png","@":{"size":"extralarge"}}]},"userplaycount":"1","userloved":"0","toptags":{"tag":[{"name":"Conscious Hip Hop","url":"https://www.last.fm/tag/Conscious+Hip+Hop"},{"name":"west coast hip hop","url":"https://www.last.fm/tag/west+coast+hip+hop"}]},"wiki":{"published":"14 Jun 2024, 23:11","summary":"In this short story of romantic serendipity and heartbreak, Vince takes us through the short-lived love journey he has with a lady he meets from Qatar who is disingenuous to him and others involved with her. Vince’s verse is fast-paced and brief to vividly describe to the audience how quickly things progressed in their relationship. The audience is taken on this roller coaster of emotions that moves so fast that if you blink you’ll miss the moments he nearly fell in love, pulled out a gun, or realized he was being lied to the whole time. Read more on Last.fm.","content":"In this short story of romantic serendipity and heartbreak, Vince takes us through the short-lived love journey he has with a lady he meets from Qatar who is disingenuous to him and others involved with her. Vince’s verse is fast-paced and brief to vividly describe to the audience how quickly things progressed in their relationship. The audience is taken on this roller coaster of emotions that moves so fast that if you blink you’ll miss the moments he nearly fell in love, pulled out a gun, or realized he was being lied to the whole time. Read more on Last.fm. User-contributed text is available under the Creative Commons By-SA License; additional terms may apply."}}} Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPrevious Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::previous Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: [1720656530043] ControllerVolusonic::seek to 0 Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::seek Jul 11 10:08:50 volumio volumio[1712]: error: null Jul 11 10:08:50 volumio volumio[1712]: info: [1720656530046] ControllerVolusonic: MPD player state update Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: Jul 11 10:08:50 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: Jul 11 10:08:50 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: Jul 11 10:08:50 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 3 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 3 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 3 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: [1720656530053] ControllerVolusonic::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":124,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1265 Kbps","isStreaming":false,"title":"Justin","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=8bcf1b52d7dde07ca749e526842e879f&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:50 volumio volumio[1712]: verbose: CURRENT POSITION 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus play Jul 11 10:08:50 volumio volumio[1712]: info: Received an update from plugin. extracting info from payload Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:50 volumio volumio[1712]: info: ------------------------------ 14ms Jul 11 10:08:50 volumio volumio[1712]: info: ------------------------------ 13ms Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:50 volumio volumio[1712]: info: ------------------------------ 14ms Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPrevious Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::previous Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::stop Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::stPlaybackTimer Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::updateTrackBlock Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrackBlock Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::serviceStop Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::serviceStop Jul 11 10:08:50 volumio volumio[1712]: info: [1720656530788] ControllerVolusonic::stop Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::stop Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: info: [1720656530796] ControllerVolusonic: MPD player state update Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: Jul 11 10:08:50 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand stop took 9 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: info: Jul 11 10:08:50 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: Jul 11 10:08:50 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: [1720656530804] ControllerVolusonic::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: 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":"Justin","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=8bcf1b52d7dde07ca749e526842e879f&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:50 volumio volumio[1712]: verbose: CURRENT POSITION 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::syncState stateService stop Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus stop Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: No code Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:50 volumio volumio[1712]: info: [1720656530809] ControllerVolusonic::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: 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":"Justin","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=8bcf1b52d7dde07ca749e526842e879f&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:50 volumio volumio[1712]: verbose: CURRENT POSITION 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::syncState stateService stop Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus stop Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: No code Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: ------------------------------ 24ms Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 11 10:08:50 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:50 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:50 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:50 volumio volumio[1712]: info: CorePlayQueue::getTrack 7 Jul 11 10:08:50 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:50 volumio volumio[1712]: info: ------------------------------ 27ms Jul 11 10:08:50 volumio volumio[1712]: info: ------------------------------ 25ms Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:50 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::play index undefined Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::startPlaybackTimer Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: [1720656531289] ControllerVolusonic::clearAddPlayTrack Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand stop took 1 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand clear Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand clear took 1 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic" Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: error: updateQueue error: null Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 3ms Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic" Jul 11 10:08:51 volumio volumio[1712]: error: updateQueue error: null Jul 11 10:08:51 volumio volumio[1712]: error: updateQueue error: null Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 32ms Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand add "http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic" took 1 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand play Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 3ms Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand play took 3 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 2ms Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 1ms Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: info: [1720656531329] ControllerVolusonic::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: 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=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:51 volumio volumio[1712]: verbose: CURRENT POSITION 6 Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus stop Jul 11 10:08:51 volumio volumio[1712]: info: [1720656531342] ControllerVolusonic: MPD player state update Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 24 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 13 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 13 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 12 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 6 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 5 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: [1720656531375] ControllerVolusonic::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":57,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:51 volumio volumio[1712]: verbose: CURRENT POSITION 6 Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus play Jul 11 10:08:51 volumio volumio[1712]: info: Received an update from plugin. extracting info from payload Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 27ms Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 26ms Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 25ms Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 16ms Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 14ms Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 13ms Jul 11 10:08:51 volumio volumio[1712]: info: [LastFM] Current track has sufficient metadata: title (Liars) and artist (Vince Staples) passed on explicitly Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::volumioGetQueue Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::getQueue Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getQueue Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: info: [1720656531657] ControllerVolusonic: MPD player state update Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:51 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:51 volumio volumio[1712]: info: Jul 11 10:08:51 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 4 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 5ms Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 4 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 3ms Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 2ms Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 11 10:08:51 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: [1720656531665] ControllerVolusonic::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":57,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1111 Kbps","isStreaming":false,"title":"Liars","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:51 volumio volumio[1712]: verbose: CURRENT POSITION 6 Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus play Jul 11 10:08:51 volumio volumio[1712]: info: Received an update from plugin. extracting info from payload Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:51 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 14ms Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 13ms Jul 11 10:08:51 volumio volumio[1712]: info: ------------------------------ 12ms Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:51 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:51 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:08:51 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:52 volumio volumio[1712]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Liars","url":"https://www.last.fm/music/Vince+Staples/_/Liars","duration":"57000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"60863","playcount":"180962","artist":{"name":"Vince Staples","mbid":"78e854b8-9713-4ff2-9218-6b3784893bff","url":"https://www.last.fm/music/Vince+Staples"},"album":{"artist":"Vince Staples","title":"Dark Times","url":"https://www.last.fm/music/Vince+Staples/Dark+Times","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/e47493caab2fee23efbb60413d654cee.png","@":{"size":"extralarge"}}]},"userplaycount":"2","userloved":"0","toptags":""}} Jul 11 10:08:55 volumio volumio[1712]: info: CoreCommandRouter::volumioPrevious Jul 11 10:08:55 volumio volumio[1712]: info: CoreStateMachine::previous Jul 11 10:08:55 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:55 volumio volumio[1712]: info: [1720656535617] ControllerVolusonic::seek to 0 Jul 11 10:08:55 volumio volumio[1712]: info: ControllerMpd::seek Jul 11 10:08:55 volumio volumio[1712]: error: null Jul 11 10:08:55 volumio volumio[1712]: info: [1720656535619] ControllerVolusonic: MPD player state update Jul 11 10:08:55 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:55 volumio volumio[1712]: info: Jul 11 10:08:55 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:55 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:55 volumio volumio[1712]: info: Jul 11 10:08:55 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:55 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:55 volumio volumio[1712]: info: Jul 11 10:08:55 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:55 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:55 volumio volumio[1712]: info: sendMpdCommand status took 3 milliseconds Jul 11 10:08:55 volumio volumio[1712]: info: sendMpdCommand status took 3 milliseconds Jul 11 10:08:55 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:55 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:55 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:55 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:55 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:55 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:55 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:55 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:55 volumio volumio[1712]: info: [1720656535627] ControllerVolusonic::pushState Jul 11 10:08:55 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:55 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:55 volumio volumio[1712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":57,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1209 Kbps","isStreaming":false,"title":"Liars","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:55 volumio volumio[1712]: verbose: CURRENT POSITION 6 Jul 11 10:08:55 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:55 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus play Jul 11 10:08:55 volumio volumio[1712]: info: Received an update from plugin. extracting info from payload Jul 11 10:08:55 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:55 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:55 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:08:55 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:55 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:55 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:55 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:55 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:55 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:55 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:55 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:55 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:55 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:55 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:55 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:55 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:55 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:55 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:55 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:55 volumio volumio[1712]: info: ------------------------------ 20ms Jul 11 10:08:55 volumio volumio[1712]: info: ------------------------------ 19ms Jul 11 10:08:55 volumio volumio[1712]: info: ------------------------------ 18ms Jul 11 10:08:55 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:55 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:55 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:55 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:55 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:55 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:55 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:55 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPrevious Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::previous Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::stop Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::stPlaybackTimer Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::updateTrackBlock Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrackBlock Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::serviceStop Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::serviceStop Jul 11 10:08:56 volumio volumio[1712]: info: [1720656536051] ControllerVolusonic::stop Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::stop Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: info: [1720656536058] ControllerVolusonic: MPD player state update Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand stop took 8 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 3 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: [1720656536069] ControllerVolusonic::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: 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":"Liars","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:56 volumio volumio[1712]: verbose: CURRENT POSITION 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::syncState stateService stop Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus stop Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: No code Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: [1720656536078] ControllerVolusonic::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: 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":"Liars","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=ee06309110d89dfdaad8f7d0916f8b06&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:56 volumio volumio[1712]: verbose: CURRENT POSITION 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::syncState stateService stop Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus stop Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: No code Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 34ms Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 6 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 36ms Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 34ms Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::play index undefined Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::startPlaybackTimer Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: [1720656536552] ControllerVolusonic::clearAddPlayTrack Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand stop Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand stop took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand clear Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:56 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand clear took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.10.237:4533/rest/stream.view?id=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic" Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:56 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:56 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:56 volumio volumio[1712]: error: updateQueue error: null Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 3ms Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.10.237:4533/rest/stream.view?id=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic" Jul 11 10:08:56 volumio volumio[1712]: error: updateQueue error: null Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:56 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 65ms Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 64ms Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand add "http://192.168.10.237:4533/rest/stream.view?id=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic" took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand play Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:56 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:56 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 3ms Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand play took 3 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 2ms Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 2ms Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 0 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: info: [1720656536623] ControllerVolusonic::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: 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=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.10.237:4533/rest/stream.view?id=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:56 volumio volumio[1712]: verbose: CURRENT POSITION 5 Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus stop Jul 11 10:08:56 volumio volumio[1712]: info: [1720656536679] ControllerVolusonic: MPD player state update Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 25 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 24 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 24 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 23 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: [1720656536707] ControllerVolusonic::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.10.237:4533/rest/stream.view?id=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:56 volumio volumio[1712]: verbose: CURRENT POSITION 5 Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus play Jul 11 10:08:56 volumio volumio[1712]: info: Received an update from plugin. extracting info from payload Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 34ms Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 33ms Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 32ms Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: Jul 11 10:08:56 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand status took 1 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 13ms Jul 11 10:08:56 volumio volumio[1712]: info: [LastFM] Current track has sufficient metadata: title (Étouffée) and artist (Vince Staples) passed on explicitly Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::volumioGetQueue Jul 11 10:08:56 volumio volumio[1712]: info: CoreStateMachine::getQueue Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getQueue Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:56 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 11 10:08:56 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:56 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:56 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:56 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 18ms Jul 11 10:08:56 volumio volumio[1712]: info: ------------------------------ 17ms Jul 11 10:08:57 volumio volumio[1712]: info: Jul 11 10:08:57 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:57 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:57 volumio volumio[1712]: info: [1720656537011] ControllerVolusonic: MPD player state update Jul 11 10:08:57 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:57 volumio volumio[1712]: info: Jul 11 10:08:57 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:57 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:57 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:57 volumio volumio[1712]: info: ------------------------------ 3ms Jul 11 10:08:57 volumio volumio[1712]: info: sendMpdCommand status took 2 milliseconds Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:57 volumio volumio[1712]: info: Jul 11 10:08:57 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:57 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:57 volumio volumio[1712]: info: Jul 11 10:08:57 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:57 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:57 volumio volumio[1712]: info: Jul 11 10:08:57 volumio volumio[1712]: ---------------------------- MPD announces system playlist update Jul 11 10:08:57 volumio volumio[1712]: info: Ignoring MPD Status Update Jul 11 10:08:57 volumio volumio[1712]: info: Jul 11 10:08:57 volumio volumio[1712]: ---------------------------- MPD announces state update: player Jul 11 10:08:57 volumio volumio[1712]: info: ControllerMpd::getState Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand status Jul 11 10:08:57 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 10:08:57 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 11 10:08:57 volumio volumio[1712]: info: ------------------------------ 6ms Jul 11 10:08:57 volumio volumio[1712]: info: sendMpdCommand status took 6 milliseconds Jul 11 10:08:57 volumio volumio[1712]: info: ------------------------------ 5ms Jul 11 10:08:57 volumio volumio[1712]: info: sendMpdCommand status took 5 milliseconds Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::parseState Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 10:08:57 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:57 volumio volumio[1712]: info: [1720656537025] ControllerVolusonic::pushState Jul 11 10:08:57 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:57 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:57 volumio volumio[1712]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":268,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1114 Kbps","isStreaming":false,"title":"Étouffée","artist":"Vince Staples","album":"Dark Times","uri":"http://192.168.10.237:4533/rest/stream.view?id=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic","trackType":""} Jul 11 10:08:57 volumio volumio[1712]: verbose: CURRENT POSITION 5 Jul 11 10:08:57 volumio volumio[1712]: info: CoreStateMachine::syncState stateService play Jul 11 10:08:57 volumio volumio[1712]: info: CoreStateMachine::syncState currentStatus play Jul 11 10:08:57 volumio volumio[1712]: info: Received an update from plugin. extracting info from payload Jul 11 10:08:57 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:57 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:57 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 10:08:57 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:57 volumio volumio[1712]: info: CoreStateMachine::pushState Jul 11 10:08:57 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:57 volumio volumio[1712]: info: CoreCommandRouter::volumioPushState Jul 11 10:08:57 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:57 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:57 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:57 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:57 volumio volumio[1712]: info: ------------------------------ 22ms Jul 11 10:08:57 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 11 10:08:57 volumio volumio[1712]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:57 volumio volumio[1712]: verbose: ControllerMpd::parseTrackInfo Jul 11 10:08:57 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:57 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:57 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:57 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:57 volumio volumio[1712]: info: ControllerMpd::pushState Jul 11 10:08:57 volumio volumio[1712]: info: CoreCommandRouter::servicePushState Jul 11 10:08:57 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:08:57 volumio volumio[1712]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jul 11 10:08:57 volumio volumio[1712]: info: ------------------------------ 20ms Jul 11 10:08:57 volumio volumio[1712]: info: ------------------------------ 20ms Jul 11 10:08:57 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:57 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:57 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:57 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:57 volumio volumio[1712]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Jul 11 10:08:57 volumio volumio[1712]: SPOTIFY: SPOTIFY VOLUME 52 Jul 11 10:08:57 volumio volumio[1712]: SPOTIFY: VOLUMIO VOLUME 52 Jul 11 10:08:57 volumio volumio[1712]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 11 10:08:58 volumio volumio[1712]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Étouffée","url":"https://www.last.fm/music/Vince+Staples/_/%C3%89touff%C3%A9e","duration":"268000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"74725","playcount":"425823","artist":{"name":"Vince Staples","mbid":"78e854b8-9713-4ff2-9218-6b3784893bff","url":"https://www.last.fm/music/Vince+Staples"},"album":{"artist":"Vince Staples","title":"Dark Times","url":"https://www.last.fm/music/Vince+Staples/Dark+Times","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/e47493caab2fee23efbb60413d654cee.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/e47493caab2fee23efbb60413d654cee.png","@":{"size":"extralarge"}}]},"userplaycount":"3","userloved":"0","toptags":{"tag":[{"name":"Conscious Hip Hop","url":"https://www.last.fm/tag/Conscious+Hip+Hop"},{"name":"west coast hip hop","url":"https://www.last.fm/tag/west+coast+hip+hop"}]}}} Jul 11 10:09:01 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:09:01 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:09:01 volumio volumio[1712]: info: Listing playlists Jul 11 10:09:01 volumio volumio[1712]: info: Listing playlists Jul 11 10:09:06 volumio volumio[1712]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Jul 11 10:09:06 volumio volumio[1712]: info: Preload queue cleared Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/d8e431893893873edea42d38d8ce714b Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/8ebbee4db9dd23e4c9c5157cfe782f29 Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/0d68f6ccf06e4b9ca488bbda59aa3835 Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/b615d46305b2db42635c5a36df93cf96 Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/0dafdc9314d45df82a4483c684dad825 Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/f2cf5f8d43ed8baf91e0932a97a391b3 Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/ee06309110d89dfdaad8f7d0916f8b06 Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/8bcf1b52d7dde07ca749e526842e879f Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/8f3465ccc1d248fc31a843706c65af1e Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/0a41e237ba7907c6ead006491a825e47 Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/663801ad2d8dd1a5c4c28c51a4a1a97f Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/2d72d8c33547c6310204a32f917c3cc9 Jul 11 10:09:06 volumio volumio[1712]: info: Preloading song: volusonic/track/14680f170265bb8606d90a7abf21da18 Jul 11 10:09:06 volumio volumio[1712]: info: Exploding uri volusonic/track/d8e431893893873edea42d38d8ce714b in service volusonic Jul 11 10:09:06 volumio volumio[1712]: info: No valid Plugin REST Endpoint Jul 11 10:09:06 volumio volumio[1712]: info: Exploding uri volusonic/track/8ebbee4db9dd23e4c9c5157cfe782f29 in service volusonic Jul 11 10:09:06 volumio volumio[1712]: info: Exploding uri volusonic/track/0d68f6ccf06e4b9ca488bbda59aa3835 in service volusonic Jul 11 10:09:06 volumio volumio[1712]: info: Exploding uri volusonic/track/b615d46305b2db42635c5a36df93cf96 in service volusonic Jul 11 10:09:06 volumio volumio[1712]: info: Exploding uri volusonic/track/0dafdc9314d45df82a4483c684dad825 in service volusonic Jul 11 10:09:06 volumio volumio[1712]: info: Exploding uri volusonic/track/f2cf5f8d43ed8baf91e0932a97a391b3 in service volusonic Jul 11 10:09:06 volumio volumio[1712]: info: Exploding uri volusonic/track/ee06309110d89dfdaad8f7d0916f8b06 in service volusonic Jul 11 10:09:06 volumio volumio[1712]: info: Exploding uri volusonic/track/8bcf1b52d7dde07ca749e526842e879f in service volusonic Jul 11 10:09:07 volumio volumio[1712]: info: Exploding uri volusonic/track/8f3465ccc1d248fc31a843706c65af1e in service volusonic Jul 11 10:09:07 volumio volumio[1712]: info: Exploding uri volusonic/track/0a41e237ba7907c6ead006491a825e47 in service volusonic Jul 11 10:09:07 volumio volumio[1712]: info: Exploding uri volusonic/track/663801ad2d8dd1a5c4c28c51a4a1a97f in service volusonic Jul 11 10:09:07 volumio volumio[1712]: info: Exploding uri volusonic/track/2d72d8c33547c6310204a32f917c3cc9 in service volusonic Jul 11 10:09:07 volumio volumio[1712]: info: Exploding uri volusonic/track/14680f170265bb8606d90a7abf21da18 in service volusonic Jul 11 10:09:11 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:09:11 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:09:11 volumio volumio[1712]: info: CoreCommandRouter::volumioGetState Jul 11 10:09:11 volumio volumio[1712]: info: CorePlayQueue::getTrack 5 Jul 11 10:09:12 volumio volumio[1712]: info: Adding http://192.168.10.237:4533/rest/stream.view?id=f2cf5f8d43ed8baf91e0932a97a391b3&format=raw&u=Jeznado&t=80da84988c83ae949619a4e84ebf1e08&s=WJ2wB&v=1.10.2&f=json&c=volusonic to favourites with specific volusonic method Jul 11 10:09:12 volumio volumio[1712]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 10:09:12 volumio volumio[1712]: TypeError: Cannot read property 'then' of undefined Jul 11 10:09:12 volumio volumio[1712]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:672:20) Jul 11 10:09:12 volumio volumio[1712]: at Socket.emit (events.js:315:20) Jul 11 10:09:12 volumio volumio[1712]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 11 10:09:12 volumio volumio[1712]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 11 10:09:12 volumio volumio[1712]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 10:09:13 volumio sudo[21400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-11 10:08 Jul 11 10:09:13 volumio sudo[21400]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"