-- Logs begin at Thu 2019-02-14 17:12:00 +07, end at Fri 2024-10-11 15:43:38 +07. -- Oct 11 15:42:00 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Oct 11 15:42:00 music volumio[916]: info: [soundcloud] browseUri: soundcloud Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1885800153 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1906609610 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1904571989 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1885699203 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1867975344 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1794449638 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1905712205 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1908156545 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1888558911 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1768943343 Oct 11 15:42:04 music volumio[916]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=1832661483 Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: info: Preload queue cleared Oct 11 15:42:04 music volumio[916]: Searching plugin music_service/soundcloud Oct 11 15:42:04 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Oct 11 15:42:08 music volumio[916]: info: All search sources collected, pushing search results Oct 11 15:42:09 music volumio[916]: info: Preload queue cleared Oct 11 15:42:09 music volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 15:42:09 music volumio[916]: info: CoreStateMachine::ClearQueue Oct 11 15:42:09 music volumio[916]: info: CoreStateMachine::stop Oct 11 15:42:09 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:42:09 music volumio[916]: info: CorePlayQueue::clearPlayQueue Oct 11 15:42:09 music volumio[916]: info: CorePlayQueue::saveQueue Oct 11 15:42:09 music volumio[916]: info: CoreCommandRouter::volumioPushQueue Oct 11 15:42:09 music volumio[916]: info: CoreStateMachine::addQueueItems Oct 11 15:42:09 music volumio[916]: info: CorePlayQueue::addQueueItems Oct 11 15:42:09 music volumio[916]: info: Preload queue cleared Oct 11 15:42:09 music volumio[916]: info: Adding Item to queue: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818 Oct 11 15:42:09 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818 in service soundcloud Oct 11 15:42:09 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818 Oct 11 15:42:09 music volumio[916]: info: Preload queue cleared Oct 11 15:42:09 music volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 15:42:09 music volumio[916]: info: CoreStateMachine::ClearQueue Oct 11 15:42:09 music volumio[916]: info: CoreStateMachine::stop Oct 11 15:42:09 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:42:09 music volumio[916]: info: CorePlayQueue::clearPlayQueue Oct 11 15:42:09 music volumio[916]: info: CorePlayQueue::saveQueue Oct 11 15:42:09 music volumio[916]: info: CoreCommandRouter::volumioPushQueue Oct 11 15:42:09 music volumio[916]: info: CoreStateMachine::addQueueItems Oct 11 15:42:09 music volumio[916]: info: CorePlayQueue::addQueueItems Oct 11 15:42:09 music volumio[916]: info: Preload queue cleared Oct 11 15:42:09 music volumio[916]: info: Adding Item to queue: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818 Oct 11 15:42:09 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818 in service soundcloud Oct 11 15:42:09 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1930244402 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1921722314 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1918048754 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1914559901 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1913176958 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1912651874 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1911392837 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1910769557 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1909996931 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1908675143 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1905404105 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1903752785 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1903175770 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1900199436 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1898989380 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1897763658 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1897692900 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1896970722 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1895417931 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1893276828 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1891942038 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1891151238 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1890240726 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1889154225 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1888156335 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1885322289 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1882843737 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1879618614 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1877387259 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1874971131 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1873240008 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1872224520 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1871607684 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1867837623 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1866080178 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1864525845 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860831567 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860833475 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860849849 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860791742 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831101837 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831104705 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831108161 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831112934 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831116450 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1830700749 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1830705096 Oct 11 15:42:10 music volumio[916]: info: CoreCommandRouter::volumioPushQueue Oct 11 15:42:10 music volumio[916]: info: CorePlayQueue::saveQueue Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::updateTrackBlock Oct 11 15:42:10 music volumio[916]: info: CorePlayQueue::getTrackBlock Oct 11 15:42:10 music volumio[916]: info: CoreCommandRouter::volumioPlay Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::play index 0 Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::stop Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::play index undefined Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:42:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::startPlaybackTimer Oct 11 15:42:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1930244402 Oct 11 15:42:10 music volumio[916]: info: [soundcloud-testing] Transcodings for 1930244402 - Purple shower Oct 11 15:42:10 music volumio[916]: info: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1930244402/0789ad35-76a8-4d1d-9e8b-c3f7e4319bb5/stream/hls","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1930244402/0789ad35-76a8-4d1d-9e8b-c3f7e4319bb5/stream/progressive","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1930244402/96dd59ff-9867-4a43-b7d3-d5824efa6a04/stream/hls","protocol":"hls","mimeType":"audio/ogg; codecs=\"opus\"","quality":"sq"}] Oct 11 15:42:10 music volumio[916]: info: [soundcloud-testing] Chosen transcoding: {"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1930244402/0789ad35-76a8-4d1d-9e8b-c3f7e4319bb5/stream/progressive","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"} Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1930244402 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1921722314 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1918048754 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1914559901 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1913176958 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1912651874 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1911392837 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1910769557 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1909996931 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1908675143 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1905404105 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1903752785 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1903175770 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1900199436 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1898989380 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1897763658 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1897692900 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1896970722 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1895417931 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1893276828 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1891942038 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1891151238 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1890240726 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1889154225 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1888156335 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1885322289 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1882843737 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1879618614 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1877387259 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1874971131 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1873240008 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1872224520 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1871607684 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1867837623 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1866080178 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1864525845 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860831567 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860833475 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860849849 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1860791742 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831101837 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831104705 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831108161 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831112934 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1831116450 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1830700749 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1830705096 Oct 11 15:42:10 music volumio[916]: info: CoreCommandRouter::volumioPushQueue Oct 11 15:42:10 music volumio[916]: info: CorePlayQueue::saveQueue Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::updateTrackBlock Oct 11 15:42:10 music volumio[916]: info: CorePlayQueue::getTrackBlock Oct 11 15:42:10 music volumio[916]: info: CoreCommandRouter::volumioPlay Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::play index 0 Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::stop Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::play index undefined Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:42:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:10 music volumio[916]: info: CoreStateMachine::startPlaybackTimer Oct 11 15:42:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:10 music volumio[916]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1930244402 Oct 11 15:42:10 music volumio[916]: info: [soundcloud-testing] Transcodings for 1930244402 - Purple shower Oct 11 15:42:10 music volumio[916]: info: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1930244402/0789ad35-76a8-4d1d-9e8b-c3f7e4319bb5/stream/hls","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1930244402/0789ad35-76a8-4d1d-9e8b-c3f7e4319bb5/stream/progressive","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1930244402/96dd59ff-9867-4a43-b7d3-d5824efa6a04/stream/hls","protocol":"hls","mimeType":"audio/ogg; codecs=\"opus\"","quality":"sq"}] Oct 11 15:42:10 music volumio[916]: info: [soundcloud-testing] Chosen transcoding: {"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1930244402/0789ad35-76a8-4d1d-9e8b-c3f7e4319bb5/stream/progressive","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"} Oct 11 15:42:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand stop took 9 milliseconds Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand clear took 2 milliseconds Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand addid "https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 9 milliseconds Oct 11 15:42:11 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:42:11 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:42:11 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 35ms Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 32ms Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 19ms Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 17ms Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 15ms Oct 11 15:42:11 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand play Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 26ms Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand play took 18 milliseconds Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 15ms Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 13ms Oct 11 15:42:11 music volumio[916]: info: ------------------------------ 10ms Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand stop took 539 milliseconds Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:11 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:11 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:11 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:11 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:11 music volumio[916]: info: Oct 11 15:42:11 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:11 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand clear took 18 milliseconds Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand status took 16 milliseconds Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand status took 14 milliseconds Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand status took 11 milliseconds Oct 11 15:42:11 music volumio[916]: info: sendMpdCommand status took 9 milliseconds Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:11 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:12 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:12 music volumio[916]: 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} Oct 11 15:42:12 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: No code Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:12 music volumio[916]: 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} Oct 11 15:42:12 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: No code Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:12 music volumio[916]: 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} Oct 11 15:42:12 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: No code Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:12 music volumio[916]: 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} Oct 11 15:42:12 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: No code Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 314ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 312ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 310ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 309ms Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: error: updateQueue error: null Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 322ms Oct 11 15:42:12 music volumio[916]: info: sendMpdCommand addid "https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 315 milliseconds Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 15ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 13ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 12ms Oct 11 15:42:12 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:42:12 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:42:12 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Oct 11 15:42:12 music volumio[916]: info: [soundcloud] browseUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818 Oct 11 15:42:12 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Oct 11 15:42:12 music volumio[916]: info: [soundcloud] browseUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818 Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 193ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 185ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 183ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 182ms Oct 11 15:42:12 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 15:42:12 music volumio[916]: verbose: ControllerMpd::sendMpdCommand play Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: Oct 11 15:42:12 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:42:12 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 19ms Oct 11 15:42:12 music volumio[916]: info: sendMpdCommand play took 15 milliseconds Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 14ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 11ms Oct 11 15:42:12 music volumio[916]: info: ------------------------------ 10ms Oct 11 15:42:14 music volumio[916]: info: Oct 11 15:42:14 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:14 music volumio[916]: info: Oct 11 15:42:14 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:14 music volumio[916]: info: Oct 11 15:42:14 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:14 music volumio[916]: info: Oct 11 15:42:14 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:14 music volumio[916]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 11 15:42:14 music volumio[916]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 11 15:42:14 music volumio[916]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 11 15:42:14 music volumio[916]: info: FusionDsp - Effects disabled Oct 11 15:42:14 music volumio[916]: info: Oct 11 15:42:14 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand status took 77 milliseconds Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand status took 76 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand status took 73 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand status took 70 milliseconds Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:42:14 music volumio[916]: info: Oct 11 15:42:14 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:14 music volumio[916]: info: Oct 11 15:42:14 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:14 music volumio[916]: info: Oct 11 15:42:14 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::getState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand status took 39 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand status took 30 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand status took 25 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand status took 22 milliseconds Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:14 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:14 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":529,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:42:14 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:14 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:14 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":529,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:42:14 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:42:14 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:14 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:14 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":529,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:42:14 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:42:14 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:14 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:14 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":529,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:42:14 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:42:14 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: ------------------------------ 194ms Oct 11 15:42:14 music volumio[916]: info: ------------------------------ 339ms Oct 11 15:42:14 music volumio[916]: info: ------------------------------ 336ms Oct 11 15:42:14 music volumio[916]: info: ------------------------------ 335ms Oct 11 15:42:14 music volumio[916]: info: Preload queue cleared Oct 11 15:42:14 music volumio[916]: info: Preload queue cleared Oct 11 15:42:14 music volumio[916]: info: Preload queue cleared Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1930244402 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1921722314 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1918048754 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1914559901 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1913176958 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1912651874 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1911392837 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1910769557 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1909996931 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1908675143 Oct 11 15:42:14 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1905404105 Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand playlistinfo took 250 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand playlistinfo took 243 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand playlistinfo took 243 milliseconds Oct 11 15:42:14 music volumio[916]: info: sendMpdCommand playlistinfo took 243 milliseconds Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:42:14 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:14 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:14 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":529,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:42:14 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:42:14 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:14 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:14 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":529,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:42:14 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:42:14 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:14 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:14 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":529,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:42:14 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:42:14 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:42:14 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:42:14 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":529,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:42:14 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:42:14 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:42:14 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:42:14 music volumio[916]: info: ------------------------------ 561ms Oct 11 15:42:14 music volumio[916]: info: ------------------------------ 548ms Oct 11 15:42:14 music volumio[916]: info: ------------------------------ 546ms Oct 11 15:42:14 music volumio[916]: info: ------------------------------ 543ms Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1930244402 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1930244402 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1930244402 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1921722314 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1921722314 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1921722314 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1918048754 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1918048754 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1918048754 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1914559901 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1914559901 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1914559901 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1913176958 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1913176958 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1913176958 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1912651874 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1912651874 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1912651874 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1911392837 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1911392837 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1911392837 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1910769557 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1910769557 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1910769557 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1909996931 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1909996931 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1909996931 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1908675143 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1908675143 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1908675143 Oct 11 15:42:15 music volumio[916]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 11 15:42:15 music volumio[916]: info: Preload queue cleared Oct 11 15:42:15 music volumio[916]: info: Preload queue cleared Oct 11 15:42:15 music volumio[916]: info: Preload queue cleared Oct 11 15:42:15 music volumio[916]: info: Preloading song: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1905404105 Oct 11 15:42:15 music volumio[916]: info: Exploding uri soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1905404105 in service soundcloud Oct 11 15:42:15 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/users@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20users%20matching%20'%C4%91%C3%A2s'/users@userId=964253818/tracks@userId=964253818@inSection=1@title=Tracks/track@trackId=1905404105 Oct 11 15:42:15 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1905404105 Oct 11 15:42:17 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:24 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 15:42:24 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 15:42:24 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:24 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:24 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:29 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:29 music volumio[916]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Oct 11 15:42:36 music volumio-remote-updater[618]: [2024-10-11 15:42:36] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 11 15:42:41 music volumio-remote-updater[618]: [2024-10-11 15:42:41] [connect] Successful connection Oct 11 15:42:43 music volumio[916]: info: [ytmusic] InnertubeLoader: creating Auth instance... Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:43 music volumio[916]: info: [ytmusic] Auth success Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:43 music volumio[916]: info: [ytmusic] InnertubeLoader: Auth instance created (status: signed in) Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:43 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:42:43 music volumio[916]: verbose: New Socket.io Connection to 192.168.1.245 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 11 15:42:43 music volumio-remote-updater[618]: [2024-10-11 15:42:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728636161 101 Oct 11 15:42:43 music volumio[916]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Oct 11 15:42:43 music volumio[916]: verbose: New Socket.io Connection to 192.168.1.245 from 192.168.1.72 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 11 15:42:43 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:42:43 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:42:43 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:43 music volumio[916]: info: Received Get System Info Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 15:42:43 music volumio[916]: info: Discovery: Getting this device information Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 15:42:43 music volumio[916]: info: Received Get System Info Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 15:42:43 music volumio[916]: info: Discovery: Getting this device information Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 15:42:43 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:43 music volumio[916]: info: Listing playlists Oct 11 15:42:44 music volumio[916]: info: Received Get System Info Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 15:42:44 music volumio[916]: info: Discovery: Getting this device information Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetQueue Oct 11 15:42:44 music volumio[916]: info: CoreStateMachine::getQueue Oct 11 15:42:44 music volumio[916]: info: CorePlayQueue::getQueue Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 15:42:44 music volumio[916]: info: Received Get System Info Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 15:42:44 music volumio[916]: info: Discovery: Getting this device information Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: Listing playlists Oct 11 15:42:44 music volumio[916]: info: Received Get System Info Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 15:42:44 music volumio[916]: info: Discovery: Getting this device information Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetQueue Oct 11 15:42:44 music volumio[916]: info: CoreStateMachine::getQueue Oct 11 15:42:44 music volumio[916]: info: CorePlayQueue::getQueue Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 15:42:44 music volumio[916]: info: Received Get System Info Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 15:42:44 music volumio[916]: info: Discovery: Getting this device information Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: Listing playlists Oct 11 15:42:44 music volumio[916]: info: Received Get System Info Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 15:42:44 music volumio[916]: info: Discovery: Getting this device information Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::volumioGetQueue Oct 11 15:42:44 music volumio[916]: info: CoreStateMachine::getQueue Oct 11 15:42:44 music volumio[916]: info: CorePlayQueue::getQueue Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 15:42:44 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:42:45 music volumio[916]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 11 15:42:45 music volumio[916]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 11 15:42:45 music volumio[916]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 11 15:42:50 music ntpd[818]: Soliciting pool server 2a0c:b847:ffff:79::e53c Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Oct 11 15:43:04 music volumio[916]: info: [soundcloud] browseUri: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Oct 11 15:43:04 music volumio[916]: info: [soundcloud] browseUri: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Oct 11 15:43:04 music volumio[916]: info: [soundcloud] browseUri: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 Oct 11 15:43:04 music volumio[916]: info: Preload queue cleared Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::ClearQueue Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::stop Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::stPlaybackTimer Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::updateTrackBlock Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::getTrackBlock Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::serviceStop Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::serviceStop Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 15:43:04 music volumio[916]: info: ControllerMpd::stop Oct 11 15:43:04 music volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::clearPlayQueue Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::saveQueue Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::volumioPushQueue Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::addQueueItems Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::addQueueItems Oct 11 15:43:04 music volumio[916]: info: Preload queue cleared Oct 11 15:43:04 music volumio[916]: info: Adding Item to queue: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 Oct 11 15:43:04 music volumio[916]: info: Exploding uri soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 in service soundcloud Oct 11 15:43:04 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 Oct 11 15:43:04 music volumio[916]: info: Preload queue cleared Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::ClearQueue Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::stop Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::clearPlayQueue Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::saveQueue Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::volumioPushQueue Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::addQueueItems Oct 11 15:43:04 music volumio[916]: info: CorePlayQueue::addQueueItems Oct 11 15:43:04 music volumio[916]: info: Preload queue cleared Oct 11 15:43:04 music volumio[916]: info: Adding Item to queue: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 Oct 11 15:43:04 music volumio[916]: info: Exploding uri soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 in service soundcloud Oct 11 15:43:04 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334 Oct 11 15:43:04 music volumio[916]: info: CoreCommandRouter::volumioPause Oct 11 15:43:04 music volumio[916]: info: CoreStateMachine::pause Oct 11 15:43:05 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:43:05 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:05 music volumio[916]: info: Oct 11 15:43:05 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:05 music volumio[916]: info: sendMpdCommand stop took 291 milliseconds Oct 11 15:43:05 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:05 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:05 music volumio[916]: info: Oct 11 15:43:05 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:05 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:05 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:05 music volumio[916]: info: Oct 11 15:43:05 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:05 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:05 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:05 music volumio[916]: info: Oct 11 15:43:05 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:05 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:05 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:06 music volumio[916]: info: sendMpdCommand status took 1147 milliseconds Oct 11 15:43:06 music volumio[916]: info: sendMpdCommand status took 1144 milliseconds Oct 11 15:43:06 music volumio[916]: info: sendMpdCommand status took 1141 milliseconds Oct 11 15:43:06 music volumio[916]: info: sendMpdCommand status took 1139 milliseconds Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:06 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:43:06 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:43:06 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:43:06 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:43:06 music volumio[916]: Upnp client error: Error: This socket has been ended by the other party Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: sendMpdCommand playlistinfo took 234 milliseconds Oct 11 15:43:06 music volumio[916]: info: sendMpdCommand playlistinfo took 234 milliseconds Oct 11 15:43:06 music volumio[916]: info: sendMpdCommand playlistinfo took 234 milliseconds Oct 11 15:43:06 music volumio[916]: info: sendMpdCommand playlistinfo took 233 milliseconds Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:06 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:06 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: 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":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:06 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: No code Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: 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":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:06 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: No code Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: 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":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:06 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: No code Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: 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":"Purple shower","artist":"uomomarco the craftsman of melody","album":"Indigo shower","uri":"https://cf-media.sndcdn.com/Wgta8HCrKDqU.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vV2d0YThIQ3JLRHFVLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NzgwfX19XX0_&Signature=XoC5xHOO0aopTNp98UeGsZEgyNHv7ZKk6irglhvnKtJh7rtZ7LKa2gjghtI8sPZcJxpxUeTVbdQTojZZa3G4CfB2hDAB~7PZAU2V8Ou4X~dgFvu14S8OG3AZlMA9bq-ouzy-~TvFoiS~~x6a6SOwfxoQgYXGQqpGg6VKgEXbR4eA1NzWNkPuNZotRYKbcLpivRAPnLjmDZlFr8HzDrpz-2TOVmqVk-r6Vglr0xVuaidTqceEUJ~PiwKkQ07foDUg8cwqG-yrPger2bFdMpF-gA4HvZ5Uy2i9OlL3P7L5xPG6lKsIyK09TdMWxY5~34Z1xZF0J3PdzcD0FulNdNzQSg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:06 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: No code Oct 11 15:43:06 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:06 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:06 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:06 music volumio[916]: info: ------------------------------ 1570ms Oct 11 15:43:06 music volumio[916]: info: ------------------------------ 1565ms Oct 11 15:43:06 music volumio[916]: info: ------------------------------ 1563ms Oct 11 15:43:06 music volumio[916]: info: ------------------------------ 1567ms Oct 11 15:43:06 music volumio[916]: info: camilladsp spawned new process with pid 2381, instance 1, run: true Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:06 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:07 music volumio[916]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915256771@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915381910@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915256018@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushQueue Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::saveQueue Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::updateTrackBlock Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrackBlock Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPlay Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::play index 0 Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::stop Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::play index undefined Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::startPlaybackTimer Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:08 music volumio[916]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1915256771@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud-testing] Transcodings for 1915256771 - ANH KHONG NHU VAY Oct 11 15:43:08 music volumio[916]: info: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1915256771/92caad07-5bde-432b-b64a-82a62e970580/stream/hls","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1915256771/92caad07-5bde-432b-b64a-82a62e970580/stream/progressive","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1915256771/492f9270-3897-4417-9f82-6bca3610e97d/stream/hls","protocol":"hls","mimeType":"audio/ogg; codecs=\"opus\"","quality":"sq"}] Oct 11 15:43:08 music volumio[916]: info: [soundcloud-testing] Chosen transcoding: {"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1915256771/92caad07-5bde-432b-b64a-82a62e970580/stream/progressive","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"} Oct 11 15:43:08 music volumio[916]: info: Preload queue cleared Oct 11 15:43:08 music volumio[916]: info: Preloading song: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915256771@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: Preloading song: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915381910@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: Preloading song: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915256018@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915256771@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915381910@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915256018@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushQueue Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::saveQueue Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::updateTrackBlock Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrackBlock Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPlay Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::play index 0 Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::stop Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::play index undefined Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::startPlaybackTimer Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:08 music volumio[916]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1915256771@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud-testing] Transcodings for 1915256771 - ANH KHONG NHU VAY Oct 11 15:43:08 music volumio[916]: info: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1915256771/92caad07-5bde-432b-b64a-82a62e970580/stream/hls","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1915256771/92caad07-5bde-432b-b64a-82a62e970580/stream/progressive","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1915256771/492f9270-3897-4417-9f82-6bca3610e97d/stream/hls","protocol":"hls","mimeType":"audio/ogg; codecs=\"opus\"","quality":"sq"}] Oct 11 15:43:08 music volumio[916]: info: [soundcloud-testing] Chosen transcoding: {"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1915256771/92caad07-5bde-432b-b64a-82a62e970580/stream/progressive","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"} Oct 11 15:43:08 music volumio[916]: info: Exploding uri soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915256771@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D in service soundcloud Oct 11 15:43:08 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915256771@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915256771@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: Exploding uri soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915381910@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D in service soundcloud Oct 11 15:43:08 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915381910@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915381910@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: Exploding uri soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915256018@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D in service soundcloud Oct 11 15:43:08 music volumio[916]: info: [soundcloud] explodeUri: soundcloud/albums@search=%C4%91%C3%A2s@combinedSearch=1@title=SoundCloud%20albums%20matching%20'%C4%91%C3%A2s'/albums@albumId=1876231334/track@trackId=1915256018@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1915256018@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A1876231334%7D Oct 11 15:43:08 music volumio[916]: info: Preload queue cleared Oct 11 15:43:08 music volumio[916]: info: Preload queue cleared Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand stop took 4 milliseconds Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand clear took 6 milliseconds Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: error: updateQueue error: null Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 23ms Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand addid "https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 19 milliseconds Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 18ms Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 16ms Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 13ms Oct 11 15:43:08 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:43:08 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:43:08 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 29ms Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 15ms Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 13ms Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 11ms Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand play Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 22ms Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand play took 16 milliseconds Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 13ms Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 11ms Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 9ms Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand stop took 4 milliseconds Oct 11 15:43:08 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand status took 6 milliseconds Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand clear took 6 milliseconds Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:08 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:08 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:08 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:08 music volumio[916]: error: updateQueue error: null Oct 11 15:43:08 music volumio[916]: info: Oct 11 15:43:08 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:08 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 23ms Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand addid "https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 18 milliseconds Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand status took 15 milliseconds Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand status took 12 milliseconds Oct 11 15:43:08 music volumio[916]: info: sendMpdCommand status took 8 milliseconds Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:08 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:08 music volumio[916]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 15:43:08 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:43:08 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:43:08 music volumio[916]: verbose: MPD COMMAND [object Object] Oct 11 15:43:08 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:08 music volumio[916]: 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} Oct 11 15:43:08 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: No code Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:08 music volumio[916]: 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} Oct 11 15:43:08 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: No code Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:08 music volumio[916]: 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} Oct 11 15:43:08 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::syncState stateService stop Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: No code Oct 11 15:43:08 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:08 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:08 music volumio[916]: info: ------------------------------ 102ms Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 215ms Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 214ms Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 211ms Oct 11 15:43:09 music volumio[916]: info: Oct 11 15:43:09 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:09 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:09 music volumio[916]: info: Oct 11 15:43:09 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:09 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:09 music volumio[916]: info: Oct 11 15:43:09 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:09 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:09 music volumio[916]: info: Oct 11 15:43:09 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:09 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 223ms Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 16ms Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 14ms Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 12ms Oct 11 15:43:09 music volumio[916]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 15:43:09 music volumio[916]: verbose: ControllerMpd::sendMpdCommand play Oct 11 15:43:09 music volumio[916]: info: Oct 11 15:43:09 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:09 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:09 music volumio[916]: info: Oct 11 15:43:09 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:09 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:09 music volumio[916]: info: Oct 11 15:43:09 music volumio[916]: ---------------------------- MPD announces system playlist update Oct 11 15:43:09 music volumio[916]: info: Ignoring MPD Status Update Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 98ms Oct 11 15:43:09 music volumio[916]: info: sendMpdCommand play took 92 milliseconds Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 90ms Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 87ms Oct 11 15:43:09 music volumio[916]: info: ------------------------------ 85ms Oct 11 15:43:10 music volumio[916]: info: Oct 11 15:43:10 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:10 music volumio[916]: info: Oct 11 15:43:10 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:10 music volumio[916]: info: Oct 11 15:43:10 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:10 music volumio[916]: info: Oct 11 15:43:10 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:10 music volumio[916]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 11 15:43:10 music volumio[916]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 11 15:43:10 music volumio[916]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 11 15:43:10 music volumio[916]: info: FusionDsp - Effects disabled Oct 11 15:43:10 music volumio[916]: info: Oct 11 15:43:10 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand status took 79 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand status took 77 milliseconds Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand status took 75 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand status took 71 milliseconds Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:10 music volumio[916]: info: Oct 11 15:43:10 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:10 music volumio[916]: info: Oct 11 15:43:10 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:10 music volumio[916]: info: Oct 11 15:43:10 music volumio[916]: ---------------------------- MPD announces state update: player Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::getState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand status took 21 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand status took 14 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand status took 12 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand status took 9 milliseconds Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseState Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:10 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANH KHONG NHU VAY","artist":"Belin Das","album":"TINH YEU","uri":"https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:10 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState currentStatus stop Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:10 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANH KHONG NHU VAY","artist":"Belin Das","album":"TINH YEU","uri":"https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:10 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:43:10 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:10 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANH KHONG NHU VAY","artist":"Belin Das","album":"TINH YEU","uri":"https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:10 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:43:10 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:10 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANH KHONG NHU VAY","artist":"Belin Das","album":"TINH YEU","uri":"https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:10 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:43:10 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: ------------------------------ 171ms Oct 11 15:43:10 music volumio[916]: info: ------------------------------ 293ms Oct 11 15:43:10 music volumio[916]: info: ------------------------------ 292ms Oct 11 15:43:10 music volumio[916]: info: ------------------------------ 290ms Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand playlistinfo took 202 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand playlistinfo took 199 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand playlistinfo took 199 milliseconds Oct 11 15:43:10 music volumio[916]: info: sendMpdCommand playlistinfo took 199 milliseconds Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:10 music volumio[916]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:10 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANH KHONG NHU VAY","artist":"Belin Das","album":"TINH YEU","uri":"https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:10 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:43:10 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:10 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANH KHONG NHU VAY","artist":"Belin Das","album":"TINH YEU","uri":"https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:10 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:43:10 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:10 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANH KHONG NHU VAY","artist":"Belin Das","album":"TINH YEU","uri":"https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:10 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:43:10 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: ControllerMpd::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::servicePushState Oct 11 15:43:10 music volumio[916]: info: CorePlayQueue::getTrack 0 Oct 11 15:43:10 music volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANH KHONG NHU VAY","artist":"Belin Das","album":"TINH YEU","uri":"https://cf-media.sndcdn.com/MPs7QGYjZQAG.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vTVBzN1FHWWpaUUFHLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4NjM2NDQzfX19XX0_&Signature=H8wJSAGNE2~BHFOa50JWy8TkPXIs13kSV-~NhbEz85nXJkxBvKnGMWgZLQOPW9tGHcGLZO8p9e6a~K~6r34g4qpL1W2aE493DDTF9ifGVyjeoLx9mqY5HkpaBu7Ii-0aHfsfqwwqFpLKMCO0FboS9SiV1ZFTNgLMX~5X9w~lHushFM7dS4trwPU0NreAKo5AN3lkyBDzsRfYcSQShwXEP3JorRlWVqT5ynv~KgeatwMiqu2rbY-U~cDFsKNiL28Gc0j8fwEdAEekGe7FQY-7k9BigxnQRJdS0LTMl7RlpQ05XXigqkJF6AWZc77nj4VYMmG6rDl2Le63PtnQTN3g7w__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 11 15:43:10 music volumio[916]: verbose: CURRENT POSITION 0 Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState stateService play Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:43:10 music volumio[916]: info: Received an update from plugin. extracting info from payload Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: CoreStateMachine::pushState Oct 11 15:43:10 music volumio[916]: info: CoreCommandRouter::volumioPushState Oct 11 15:43:10 music volumio[916]: info: ------------------------------ 479ms Oct 11 15:43:10 music volumio[916]: info: ------------------------------ 467ms Oct 11 15:43:10 music volumio[916]: info: ------------------------------ 467ms Oct 11 15:43:10 music volumio[916]: info: ------------------------------ 465ms Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 11 15:43:10 music volumio[916]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 11 15:43:12 music volumio[916]: info: CoreCommandRouter::volumioGetState Oct 11 15:43:20 music volumio[916]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 15:43:20 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 11 15:43:20 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 11 15:43:20 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 11 15:43:20 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 11 15:43:20 music volumio[916]: info: Received Get System Version Oct 11 15:43:20 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 15:43:36 music volumio[916]: info: Command Delete User Data Received Oct 11 15:43:36 music volumio[916]: info: CoreCommandRouter::executeOnPlugin: system , deleteUserData Oct 11 15:43:36 music volumio[916]: info: Created User Data delete file, rebooting Oct 11 15:43:36 music volumio[916]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 11 15:43:36 music volumio[916]: info: PLUGIN onReboot : networkfs Oct 11 15:43:36 music volumio[916]: info: PLUGIN onReboot : audiophonicsonoff Oct 11 15:43:36 music volumio[916]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 15:43:36 music volumio[916]: TypeError: Cannot read property 'writeSync' of undefined Oct 11 15:43:36 music volumio[916]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Oct 11 15:43:36 music volumio[916]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Oct 11 15:43:36 music volumio[916]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Oct 11 15:43:36 music volumio[916]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 11 15:43:36 music volumio[916]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 11 15:43:36 music volumio[916]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Oct 11 15:43:36 music volumio[916]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Oct 11 15:43:36 music volumio[916]: at /volumio/app/plugins/system_controller/system/index.js:719:26 Oct 11 15:43:36 music volumio[916]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Oct 11 15:43:36 music volumio[916]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Oct 11 15:43:36 music volumio[916]: at FSReqCallback.oncomplete (fs.js:171:23) Oct 11 15:43:36 music volumio[916]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 15:43:38 music sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 15:42 Oct 11 15:43:38 music sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"