-- Logs begin at Mon 2024-12-23 17:38:56 CET, end at Sun 2025-01-12 17:14:11 CET. -- Jan 12 17:13:03 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 17:13:04 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:06 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 17:13:08 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:08 rivo volumio[3519]: info: Preloading song: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 in service upnp_browser Jan 12 17:13:08 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 in service upnp_browser Jan 12 17:13:09 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 in service upnp_browser Jan 12 17:13:09 rivo volumio[3519]: info: Exploding uri upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 in service upnp_browser Jan 12 17:13:10 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::ClearQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::serviceStop Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::serviceStop Jan 12 17:13:10 rivo volumio[3519]: info: [1736698390562] ControllerUPNPBrowser::stop Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::clearPlayQueue Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:13:10 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/folder/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1459 Jan 12 17:13:10 rivo volumio[3519]: info: Exploding uri upnp/folder/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1459 in service upnp_browser Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand stop took 38 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 4 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 3 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 2 milliseconds Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: 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":"The Man Who Sold The World","artist":"Nirvana","album":"MTV Unplugged In New York","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1372/04.+The+Man+Who+Sold+The+World.flac","trackType":"flac"} Jan 12 17:13:10 rivo volumio[3519]: verbose: CURRENT POSITION 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: No code Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: 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":"The Man Who Sold The World","artist":"Nirvana","album":"MTV Unplugged In New York","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1372/04.+The+Man+Who+Sold+The+World.flac","trackType":"flac"} Jan 12 17:13:10 rivo volumio[3519]: verbose: CURRENT POSITION 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: No code Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: 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":"The Man Who Sold The World","artist":"Nirvana","album":"MTV Unplugged In New York","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1372/04.+The+Man+Who+Sold+The+World.flac","trackType":"flac"} Jan 12 17:13:10 rivo volumio[3519]: verbose: CURRENT POSITION 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: No code Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 3 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 72ms Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 71ms Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 71ms Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPlay Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::play index 2 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:13:10 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:10 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:10 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::play index undefined Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:10 rivo volumio[3519]: info: [1736698390778] ControllerUPNPBrowser::clearAddPlayTrack Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand stop took 11 milliseconds Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand clear took 5 milliseconds Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: error: updateQueue error: null Jan 12 17:13:10 rivo volumio[3519]: error: updateQueue error: null Jan 12 17:13:10 rivo volumio[3519]: error: updateQueue error: null Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 15ms Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 13ms Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 11ms Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand add "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" took 3 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand play Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 7ms Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand play took 6 milliseconds Jan 12 17:13:10 rivo kernel: aml_spdif_open Jan 12 17:13:10 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 5ms Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 5ms Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo kernel: set normal 512 fs /4 fs Jan 12 17:13:10 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 12 17:13:10 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 12 17:13:10 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 12 17:13:10 rivo kernel: set normal 512 fs /4 fs Jan 12 17:13:10 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 12 17:13:10 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 12 17:13:10 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:10 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:10 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:10 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 7 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 6 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 4 milliseconds Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 12 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 8 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:10 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","artist":null,"album":null,"uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:10 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:10 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","artist":null,"album":null,"uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:10 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:10 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:10 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","artist":null,"album":null,"uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:10 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:10 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 54ms Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 76ms Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 76ms Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:10 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:10 rivo volumio[3519]: info: Jan 12 17:13:10 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 70 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 70ms Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 65 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 66 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 65 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 10ms Jan 12 17:13:10 rivo volumio[3519]: info: sendMpdCommand status took 9 milliseconds Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 8ms Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:10 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:10 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:10 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:10 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:10 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:10 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:10 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:10 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 134ms Jan 12 17:13:10 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 12 17:13:10 rivo volumio[3519]: info: ------------------------------ 129ms Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:10 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:10 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: Signalling Playback active due to playback status change Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: info: sendMpdCommand status took 140 milliseconds Jan 12 17:13:11 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 134 milliseconds Jan 12 17:13:11 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 135 milliseconds Jan 12 17:13:11 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 133 milliseconds Jan 12 17:13:11 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:11 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:11 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:11 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:11 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:11 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:11 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"606 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:11 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:11 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:11 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:11 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"556 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:11 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:11 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:11 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:11 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"511 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:11 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:11 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:11 rivo volumio[3519]: info: ------------------------------ 279ms Jan 12 17:13:11 rivo volumio[3519]: info: ------------------------------ 276ms Jan 12 17:13:11 rivo volumio[3519]: info: ------------------------------ 215ms Jan 12 17:13:11 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 75 milliseconds Jan 12 17:13:11 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:11 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:11 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"511 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:11 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:11 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:11 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:11 rivo volumio[3519]: info: ------------------------------ 236ms Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:11 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:11 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:11 rivo volumio[3519]: info: MCU Signalled Playback Active Jan 12 17:13:15 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::ClearQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::serviceStop Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::serviceStop Jan 12 17:13:15 rivo volumio[3519]: info: [1736698395040] ControllerUPNPBrowser::stop Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::clearPlayQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:13:15 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/folder/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1459 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/folder/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1459 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPlay Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::play index 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:13:15 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:15 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 12 17:13:15 rivo kernel: spdif_a keep clk continuous Jan 12 17:13:15 rivo kernel: aml_spdif_close Jan 12 17:13:15 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:15 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:15 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::play index undefined Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: [1736698395078] ControllerUPNPBrowser::clearAddPlayTrack Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand stop took 53 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand stop took 16 milliseconds Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 4 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand clear took 3 milliseconds Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: error: updateQueue error: null Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 14ms Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 10 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 9 milliseconds Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 17:13:15 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: No code Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 17:13:15 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: No code Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 50ms Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 61ms Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 61ms Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand add "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" took 66 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 6ms Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 5ms Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand play Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo kernel: aml_spdif_open Jan 12 17:13:15 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 12 17:13:15 rivo kernel: set normal 512 fs /4 fs Jan 12 17:13:15 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 12 17:13:15 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 12 17:13:15 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 12 17:13:15 rivo kernel: set normal 512 fs /4 fs Jan 12 17:13:15 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 12 17:13:15 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 12 17:13:15 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:15 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:15 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:15 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 39ms Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand play took 36 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 35ms Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 34ms Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 7 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 5 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 4 milliseconds Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:15 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:15 rivo volumio[3519]: info: Jan 12 17:13:15 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 13ms Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 12 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 8ms Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 8 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 7ms Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:15 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:15 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:15 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:15 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:15 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 51ms Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 68ms Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 67ms Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand status took 62 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 57 milliseconds Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 55 milliseconds Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:15 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:15 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:15 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:15 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 114ms Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 109ms Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: Signalling Playback active due to playback status change Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 147 milliseconds Jan 12 17:13:15 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:15 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:15 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:15 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:15 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:15 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:15 rivo volumio[3519]: info: ------------------------------ 238ms Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 17:13:15 rivo volumio[3519]: info: Discovery: Getting this device information Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetState Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 17:13:15 rivo volumio[3519]: info: MCU Signalled Playback Inactive Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:15 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:15 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:15 rivo volumio[3519]: info: MCU Signalled Playback Active Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioPrevious Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::previous Jan 12 17:13:18 rivo volumio[3519]: info: ControllerMpd::seek Jan 12 17:13:18 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 12 17:13:18 rivo volumio[3519]: error: null Jan 12 17:13:18 rivo volumio[3519]: info: Jan 12 17:13:18 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:18 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:18 rivo volumio[3519]: info: Jan 12 17:13:18 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:18 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:18 rivo volumio[3519]: info: Jan 12 17:13:18 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:18 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:18 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:18 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:18 rivo volumio[3519]: info: sendMpdCommand status took 7 milliseconds Jan 12 17:13:18 rivo volumio[3519]: info: sendMpdCommand status took 5 milliseconds Jan 12 17:13:18 rivo volumio[3519]: info: sendMpdCommand status took 3 milliseconds Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:18 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:13:18 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:13:18 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:18 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:18 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:18 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"653 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:18 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:18 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:18 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:18 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"653 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:18 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:18 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:18 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:18 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1166,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"606 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:18 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:18 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:18 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:18 rivo volumio[3519]: info: ------------------------------ 65ms Jan 12 17:13:18 rivo volumio[3519]: info: ------------------------------ 62ms Jan 12 17:13:18 rivo volumio[3519]: info: ------------------------------ 61ms Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:18 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:18 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:18 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:18 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:18 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:18 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:18 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:18 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:18 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioPrevious Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::previous Jan 12 17:13:19 rivo volumio[3519]: info: ControllerMpd::previous Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand previous Jan 12 17:13:19 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 12 17:13:19 rivo volumio[3519]: info: sendMpdCommand previous took 11 milliseconds Jan 12 17:13:19 rivo volumio[3519]: info: Jan 12 17:13:19 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:19 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:19 rivo volumio[3519]: info: Jan 12 17:13:19 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:19 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:19 rivo volumio[3519]: info: Jan 12 17:13:19 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:19 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:19 rivo volumio[3519]: info: sendMpdCommand status took 5 milliseconds Jan 12 17:13:19 rivo volumio[3519]: info: sendMpdCommand status took 3 milliseconds Jan 12 17:13:19 rivo volumio[3519]: info: sendMpdCommand status took 2 milliseconds Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:19 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:13:19 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:13:19 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 17:13:19 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:19 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:19 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"600 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:19 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:19 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:19 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:19 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:19 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:19 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"600 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:19 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:19 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:19 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:19 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"600 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:19 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:19 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:19 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:19 rivo volumio[3519]: info: ------------------------------ 58ms Jan 12 17:13:19 rivo volumio[3519]: info: ------------------------------ 57ms Jan 12 17:13:19 rivo volumio[3519]: info: ------------------------------ 57ms Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:19 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:19 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:19 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:19 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:19 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:19 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:19 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:19 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:19 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 12 17:13:27 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::ClearQueue Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::serviceStop Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::serviceStop Jan 12 17:13:27 rivo volumio[3519]: info: [1736698407583] ControllerUPNPBrowser::stop Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::clearPlayQueue Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:13:27 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/folder/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1459 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/folder/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1459 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPlay Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::play index 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:13:27 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:27 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:27 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:27 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 12 17:13:27 rivo kernel: spdif_a keep clk continuous Jan 12 17:13:27 rivo kernel: aml_spdif_close Jan 12 17:13:27 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::play index undefined Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: [1736698407616] ControllerUPNPBrowser::clearAddPlayTrack Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:13:27 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand stop took 48 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand stop took 16 milliseconds Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 2 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand clear took 1 milliseconds Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: error: updateQueue error: null Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 22ms Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 18 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 14 milliseconds Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 17:13:27 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: No code Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 17:13:27 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: No code Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 54ms Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 65ms Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 66ms Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand add "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac" took 60 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 10ms Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 9ms Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand play Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo kernel: aml_spdif_open Jan 12 17:13:27 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 12 17:13:27 rivo kernel: set normal 512 fs /4 fs Jan 12 17:13:27 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 12 17:13:27 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 12 17:13:27 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 12 17:13:27 rivo kernel: set normal 512 fs /4 fs Jan 12 17:13:27 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 12 17:13:27 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 12 17:13:27 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:27 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:27 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:27 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:27 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:27 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:27 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:27 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:27 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:27 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 48ms Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand play took 46 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 46ms Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 44ms Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 21 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 21 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 10 milliseconds Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:27 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:27 rivo volumio[3519]: info: Jan 12 17:13:27 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:27 rivo volumio[3519]: info: MCU Signalled Playback Inactive Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 71ms Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 68 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 60 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 60 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 59 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 52ms Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 45 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 42ms Jan 12 17:13:27 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand status took 37 milliseconds Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:27 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:27 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:27 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:27 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:27 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 139ms Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 165ms Jan 12 17:13:27 rivo volumio[3519]: info: ------------------------------ 164ms Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 63 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 62 milliseconds Jan 12 17:13:27 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 63 milliseconds Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:27 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:27 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:27 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"511 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:27 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:27 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:27 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:27 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"511 Kbps","isStreaming":false,"title":"Chill Out (Things Gonna Change) [feat. Carlos Santana](2007 Remastered Version)","artist":"John Lee Hooker, Santana","album":"Chill Out (Remastered)","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1465/01+Chill+Out+%28Things+Gonna+Change%29+%5Bfeat.+Carlos+Santana%5D%282007+Remastered+Version%29+John+Lee+Hooker%2C+Santana+Chill+Out+%28Remastered%29+18+1995.flac","trackType":"flac"} Jan 12 17:13:27 rivo volumio[3519]: verbose: CURRENT POSITION 2 Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:27 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:27 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:27 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:27 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:28 rivo volumio[3519]: info: ------------------------------ 232ms Jan 12 17:13:28 rivo volumio[3519]: info: ------------------------------ 201ms Jan 12 17:13:28 rivo volumio[3519]: info: ------------------------------ 189ms Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:28 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:28 rivo volumio[3519]: info: Signalling Playback active due to playback status change Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:28 rivo volumio[3519]: info: MCU Signalled Playback Active Jan 12 17:13:29 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::ClearQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::serviceStop Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 2 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::serviceStop Jan 12 17:13:29 rivo volumio[3519]: info: [1736698409175] ControllerUPNPBrowser::stop Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::clearPlayQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:13:29 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/folder/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1459 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/folder/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1459 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1461 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPlay Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::play index 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:13:29 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1463 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1465 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1475 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1467 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1469 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1471 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1473 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1477 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1479 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1481 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1483 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1485 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1487 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1489 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1491 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1493 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1495 Jan 12 17:13:29 rivo volumio[3519]: info: Adding Item to queue: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:29 rivo volumio[3519]: info: Using cached record of: upnp/http://192.168.50.251:5001/dev/8cb6bd2f-e247-4ff5-a46a-74c1a5ba7ceb/svc/upnp-org/ContentDirectory/action@1497 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::play index undefined Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:13:29 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 12 17:13:29 rivo kernel: spdif_a keep clk continuous Jan 12 17:13:29 rivo kernel: aml_spdif_close Jan 12 17:13:29 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::startPlaybackTimer Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: info: [1736698409205] ControllerUPNPBrowser::clearAddPlayTrack Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand stop took 44 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand stop took 14 milliseconds Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 2 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand clear took 2 milliseconds Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac" Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: error: updateQueue error: null Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 11ms Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:29 rivo volumio[3519]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 16ms Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac" Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 13 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 12 milliseconds Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 17:13:29 rivo volumio[3519]: verbose: CURRENT POSITION 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: No code Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 17:13:29 rivo volumio[3519]: verbose: CURRENT POSITION 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: No code Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 54ms Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 54ms Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand add "http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac" took 56 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 7ms Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 6ms Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand play Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo kernel: aml_spdif_open Jan 12 17:13:29 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jan 12 17:13:29 rivo kernel: set normal 512 fs /4 fs Jan 12 17:13:29 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 12 17:13:29 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 12 17:13:29 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jan 12 17:13:29 rivo kernel: set normal 512 fs /4 fs Jan 12 17:13:29 rivo kernel: set spdifout clk:5644800, mpll:22579200 Jan 12 17:13:29 rivo kernel: get spdifout clk:5644797, mpll:22579186 Jan 12 17:13:29 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:29 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:29 rivo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 12 17:13:29 rivo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 48ms Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand play took 46 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 45ms Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 43ms Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 8 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 6 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 5 milliseconds Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces system playlist update Jan 12 17:13:29 rivo volumio[3519]: info: Ignoring MPD Status Update Jan 12 17:13:29 rivo volumio[3519]: info: Jan 12 17:13:29 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 15ms Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 14 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 10ms Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 8 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 8ms Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand status took 6 milliseconds Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"683 Kbps","isStreaming":false,"title":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:13:29 rivo volumio[3519]: verbose: CURRENT POSITION 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"683 Kbps","isStreaming":false,"title":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:13:29 rivo volumio[3519]: verbose: CURRENT POSITION 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:29 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"892 Kbps","isStreaming":false,"title":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:13:29 rivo volumio[3519]: verbose: CURRENT POSITION 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:29 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 60ms Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 79ms Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 79ms Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 59 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 57 milliseconds Jan 12 17:13:29 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 58 milliseconds Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:29 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"892 Kbps","isStreaming":false,"title":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:13:29 rivo volumio[3519]: verbose: CURRENT POSITION 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:29 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"892 Kbps","isStreaming":false,"title":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:13:29 rivo volumio[3519]: verbose: CURRENT POSITION 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:29 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:13:29 rivo volumio[3519]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"892 Kbps","isStreaming":false,"title":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:13:29 rivo volumio[3519]: verbose: CURRENT POSITION 1 Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState stateService play Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus play Jan 12 17:13:29 rivo volumio[3519]: info: Received an update from plugin. extracting info from payload Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:13:29 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 142ms Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 135ms Jan 12 17:13:29 rivo volumio[3519]: info: ------------------------------ 133ms Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreCommandRouter::volumioGetQueue Jan 12 17:13:29 rivo volumio[3519]: info: CoreStateMachine::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: CorePlayQueue::getQueue Jan 12 17:13:29 rivo volumio[3519]: info: Signalling Playback active due to playback status change Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:13:29 rivo volumio[3519]: info: MCU Signalled Playback Inactive Jan 12 17:13:29 rivo volumio[3519]: info: MCU Signalled Playback Active Jan 12 17:13:45 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 17:13:45 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 17:13:45 rivo volumio[3519]: info: Discovery: Getting this device information Jan 12 17:13:45 rivo volumio[3519]: info: CoreCommandRouter::volumioGetState Jan 12 17:13:45 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 17:13:54 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 17:13:54 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:13:57 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 17:13:57 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:14:01 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 17:14:01 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:14:01 rivo volumio[3519]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' Jan 12 17:14:03 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::ClearQueue Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::stPlaybackTimer Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::serviceStop Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 1 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::serviceStop Jan 12 17:14:03 rivo volumio[3519]: info: [1736698443715] ControllerUPNPBrowser::stop Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::clearPlayQueue Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::addQueueItems Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::addQueueItems Jan 12 17:14:03 rivo volumio[3519]: info: Preload queue cleared Jan 12 17:14:03 rivo volumio[3519]: info: Adding Item to queue: upnp/folder/http://192.168.50.251:9791/535bfcef-0fe3-45dd-a0d5-d5fd36456958/upnp.org-ContentDirectory-1/control@0$playlists$*p4 Jan 12 17:14:03 rivo volumio[3519]: info: Exploding uri upnp/folder/http://192.168.50.251:9791/535bfcef-0fe3-45dd-a0d5-d5fd36456958/upnp.org-ContentDirectory-1/control@0$playlists$*p4 in service upnp_browser Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jan 12 17:14:03 rivo kernel: spdif_a keep clk continuous Jan 12 17:14:03 rivo kernel: aml_spdif_close Jan 12 17:14:03 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushQueue Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::saveQueue Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::updateTrackBlock Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrackBlock Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPlay Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::play index 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::stop Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::play index undefined Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: Jan 12 17:14:03 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:14:03 rivo volumio[3519]: info: sendMpdCommand stop took 47 milliseconds Jan 12 17:14:03 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:14:03 rivo volumio[3519]: info: Jan 12 17:14:03 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:14:03 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:14:03 rivo volumio[3519]: info: Jan 12 17:14:03 rivo volumio[3519]: ---------------------------- MPD announces state update: player Jan 12 17:14:03 rivo volumio[3519]: info: ControllerMpd::getState Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand status Jan 12 17:14:03 rivo volumio[3519]: info: sendMpdCommand status took 6 milliseconds Jan 12 17:14:03 rivo volumio[3519]: info: sendMpdCommand status took 5 milliseconds Jan 12 17:14:03 rivo volumio[3519]: info: sendMpdCommand status took 3 milliseconds Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::parseState Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 17:14:03 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 17:14:03 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 17:14:03 rivo volumio[3519]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:14:03 rivo volumio[3519]: verbose: ControllerMpd::parseTrackInfo Jan 12 17:14:03 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: 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":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:14:03 rivo volumio[3519]: verbose: CURRENT POSITION 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: No code Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: 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":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:14:03 rivo volumio[3519]: verbose: CURRENT POSITION 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: No code Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: ControllerMpd::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::servicePushState Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: 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":"Chan Chan","artist":"Buena Vista Social Club","album":"Buena Vista Social Club","uri":"http://192.168.50.251:5001/ums/media/3853721d-c969-69ed-8f1d-020000203201/1463/01+Chan+Chan+Buena+Vista+Social+Club+Buena+Vista+Social+Club+03+1997.flac","trackType":"flac"} Jan 12 17:14:03 rivo volumio[3519]: verbose: CURRENT POSITION 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::syncState stateService stop Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::syncState currentStatus stop Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: No code Jan 12 17:14:03 rivo volumio[3519]: info: CoreStateMachine::pushState Jan 12 17:14:03 rivo volumio[3519]: info: CorePlayQueue::getTrack 0 Jan 12 17:14:03 rivo volumio[3519]: info: CoreCommandRouter::volumioPushState Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output update for this device Jan 12 17:14:03 rivo volumio[3519]: info: MRS: Pushing multiroomSync output Jan 12 17:14:03 rivo volumio[3519]: info: ------------------------------ 84ms Jan 12 17:14:03 rivo volumio[3519]: info: ------------------------------ 83ms Jan 12 17:14:03 rivo volumio[3519]: info: ------------------------------ 82ms Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 12 17:14:03 rivo volumio[3519]: info: MCU Signalled Playback Inactive Jan 12 17:14:10 rivo volumio[3519]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 12 17:14:10 rivo volumio[3519]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 17:14:10 rivo volumio[3519]: TypeError: Cannot read property 'length' of undefined Jan 12 17:14:10 rivo volumio[3519]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 12 17:14:10 rivo volumio[3519]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 12 17:14:10 rivo volumio[3519]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 12 17:14:10 rivo volumio[3519]: at Parser.emit (events.js:400:28) Jan 12 17:14:10 rivo volumio[3519]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 12 17:14:10 rivo volumio[3519]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 12 17:14:10 rivo volumio[3519]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 12 17:14:10 rivo volumio[3519]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 12 17:14:10 rivo volumio[3519]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 12 17:14:10 rivo volumio[3519]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 12 17:14:10 rivo volumio[3519]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 12 17:14:10 rivo volumio[3519]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 12 17:14:10 rivo volumio[3519]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 12 17:14:10 rivo volumio[3519]: at IncomingMessage.emit (events.js:412:35) Jan 12 17:14:10 rivo volumio[3519]: at endReadableNT (internal/streams/readable.js:1333:12) Jan 12 17:14:10 rivo volumio[3519]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 12 17:14:10 rivo volumio[3519]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 17:14:11 rivo sudo[21817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 17:13 Jan 12 17:14:11 rivo sudo[21817]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"