-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Sun 2025-11-02 10:40:35 MSK. -- Nov 02 10:39:02 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:06 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:10 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:14 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:16 volumio nmbd[647]: [2025/11/02 10:39:16.780834, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 02 10:39:16 volumio nmbd[647]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.192 for name WORKGROUP<1d>. Nov 02 10:39:16 volumio nmbd[647]: This response was from IP 192.168.1.3, reporting an IP address of 192.168.1.3. Nov 02 10:39:18 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:22 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:26 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:30 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:34 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:38 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:42 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:46 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:50 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:54 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:39:58 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:02 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:03 volumio volumio[718]: info: CoreCommandRouter::volumioGetState Nov 02 10:40:03 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:06 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 10:40:07 volumio volumio[718]: info: Retrieving Cloud Streaming UI Nov 02 10:40:07 volumio volumio[718]: info: Getting Tidal Cloud Configuration Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 10:40:07 volumio volumio[718]: info: Getting Qobuz Cloud Configuration Nov 02 10:40:07 volumio volumio[718]: info: Asking plugin for UI Config Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 10:40:07 volumio volumio[718]: info: Getting Spotify Cloud Configuration Nov 02 10:40:07 volumio volumio[718]: info: Asking plugin for UI Config Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 10:40:07 volumio volumio[718]: info: Saving Spotify Acccount Nov 02 10:40:07 volumio volumio[718]: info: Got Tidal Cloud Configuration Nov 02 10:40:07 volumio volumio[718]: info: Got it Nov 02 10:40:07 volumio volumio[718]: info: Got it Nov 02 10:40:07 volumio volumio[718]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 10:40:07 volumio volumio[718]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:07 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 02 10:40:08 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand update Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:08 volumio volumio[718]: info: Nov 02 10:40:08 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:08 volumio volumio[718]: info: sendMpdCommand update took 5 milliseconds Nov 02 10:40:08 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:08 volumio volumio[718]: info: Nov 02 10:40:08 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:08 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:08 volumio volumio[718]: info: Nov 02 10:40:08 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:08 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:08 volumio volumio[718]: info: sendMpdCommand status took 7 milliseconds Nov 02 10:40:08 volumio volumio[718]: info: sendMpdCommand status took 7 milliseconds Nov 02 10:40:08 volumio volumio[718]: info: sendMpdCommand status took 6 milliseconds Nov 02 10:40:08 volumio volumio[718]: info: sendMpdCommand status took 5 milliseconds Nov 02 10:40:08 volumio volumio[718]: info: sendMpdCommand status took 5 milliseconds Nov 02 10:40:08 volumio volumio[718]: info: sendMpdCommand status took 5 milliseconds Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:08 volumio volumio[718]: info: Command Router : Notfying DB Updatetrue Nov 02 10:40:08 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:08 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:09 volumio volumio[718]: info: Command Router : Notfying DB Updatetrue Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:09 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:09 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:09 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:09 volumio volumio[718]: info: Command Router : Notfying DB Updatetrue Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:09 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:09 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:09 volumio volumio[718]: info: sendMpdCommand playlistinfo took 57 milliseconds Nov 02 10:40:09 volumio volumio[718]: info: sendMpdCommand playlistinfo took 32 milliseconds Nov 02 10:40:09 volumio volumio[718]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 02 10:40:09 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:09 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:09 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:09 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285105,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"567 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:09 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:09 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:09 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285105,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"567 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:09 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:09 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:09 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285105,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"567 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:09 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:09 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:09 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:09 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:09 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:09 volumio volumio[718]: info: ------------------------------ 162ms Nov 02 10:40:09 volumio volumio[718]: info: ------------------------------ 162ms Nov 02 10:40:09 volumio volumio[718]: info: ------------------------------ 160ms Nov 02 10:40:10 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:11 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:14 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:15 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:15 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 02 10:40:15 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand update Nov 02 10:40:15 volumio volumio[718]: info: sendMpdCommand update took 12 milliseconds Nov 02 10:40:18 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:19 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:20 volumio volumio[718]: info: MPD Database updated - AlbumList cache refreshed Nov 02 10:40:20 volumio volumio[718]: info: Nov 02 10:40:20 volumio volumio[718]: ---------------------------- MPD announces state update: database Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:20 volumio volumio[718]: info: Nov 02 10:40:20 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:20 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:20 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:20 volumio volumio[718]: info: MPD Database updated - AlbumList cache refreshed Nov 02 10:40:20 volumio volumio[718]: info: Nov 02 10:40:20 volumio volumio[718]: ---------------------------- MPD announces state update: database Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:20 volumio volumio[718]: info: Nov 02 10:40:20 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:20 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:20 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:20 volumio volumio[718]: info: MPD Database updated - AlbumList cache refreshed Nov 02 10:40:20 volumio volumio[718]: info: Nov 02 10:40:20 volumio volumio[718]: ---------------------------- MPD announces state update: database Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:20 volumio volumio[718]: info: Nov 02 10:40:20 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:20 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:20 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:20 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:21 volumio volumio[718]: info: CoreCommandRouter::volumioGetState Nov 02 10:40:21 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:21 volumio volumio[718]: info: sendMpdCommand status took 979 milliseconds Nov 02 10:40:21 volumio volumio[718]: info: sendMpdCommand status took 979 milliseconds Nov 02 10:40:21 volumio volumio[718]: info: sendMpdCommand status took 978 milliseconds Nov 02 10:40:21 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:21 volumio volumio[718]: info: Command Router : Notfying DB Updatetrue Nov 02 10:40:21 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:21 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:21 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:21 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:21 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:22 volumio volumio[718]: info: sendMpdCommand status took 1580 milliseconds Nov 02 10:40:22 volumio volumio[718]: info: sendMpdCommand status took 1581 milliseconds Nov 02 10:40:22 volumio volumio[718]: info: sendMpdCommand status took 1580 milliseconds Nov 02 10:40:22 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:22 volumio volumio[718]: info: Command Router : Notfying DB Updatetrue Nov 02 10:40:22 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:22 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:22 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:22 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:22 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:22 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand status took 2343 milliseconds Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand status took 2344 milliseconds Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand status took 2343 milliseconds Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand playlistinfo took 1331 milliseconds Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand playlistinfo took 1330 milliseconds Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:23 volumio volumio[718]: info: Command Router : Notfying DB Updatetrue Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:23 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297277,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"480 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:23 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:23 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297277,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"480 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:23 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:23 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: ------------------------------ 2428ms Nov 02 10:40:23 volumio volumio[718]: info: ------------------------------ 2430ms Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand playlistinfo took 723 milliseconds Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand playlistinfo took 723 milliseconds Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand playlistinfo took 47 milliseconds Nov 02 10:40:23 volumio volumio[718]: info: sendMpdCommand playlistinfo took 48 milliseconds Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:23 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:23 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297369,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"480 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:23 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:23 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297369,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"480 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:23 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:23 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297460,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"480 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:23 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:23 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297460,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"480 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:23 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:23 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:23 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:23 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:23 volumio volumio[718]: info: ------------------------------ 2476ms Nov 02 10:40:23 volumio volumio[718]: info: ------------------------------ 2477ms Nov 02 10:40:23 volumio volumio[718]: info: ------------------------------ 2476ms Nov 02 10:40:23 volumio volumio[718]: info: ------------------------------ 2477ms Nov 02 10:40:26 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:27 volumio volumio[718]: info: MPD Database updated - AlbumList cache refreshed Nov 02 10:40:27 volumio volumio[718]: info: Nov 02 10:40:27 volumio volumio[718]: ---------------------------- MPD announces state update: database Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:27 volumio volumio[718]: info: Nov 02 10:40:27 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:27 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:27 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:27 volumio volumio[718]: info: MPD Database updated - AlbumList cache refreshed Nov 02 10:40:27 volumio volumio[718]: info: Nov 02 10:40:27 volumio volumio[718]: ---------------------------- MPD announces state update: database Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:27 volumio volumio[718]: info: Nov 02 10:40:27 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:27 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:27 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:27 volumio volumio[718]: info: MPD Database updated - AlbumList cache refreshed Nov 02 10:40:27 volumio volumio[718]: info: Nov 02 10:40:27 volumio volumio[718]: ---------------------------- MPD announces state update: database Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:27 volumio volumio[718]: info: Nov 02 10:40:27 volumio volumio[718]: ---------------------------- MPD announces state update: update Nov 02 10:40:27 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:27 volumio volumio[718]: info: ControllerMpd::getState Nov 02 10:40:27 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand status Nov 02 10:40:28 volumio volumio[718]: info: sendMpdCommand status took 918 milliseconds Nov 02 10:40:28 volumio volumio[718]: info: sendMpdCommand status took 917 milliseconds Nov 02 10:40:28 volumio volumio[718]: info: sendMpdCommand status took 917 milliseconds Nov 02 10:40:28 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:28 volumio volumio[718]: info: Command Router : Notfying DB Updatefalse Nov 02 10:40:28 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:28 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:28 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:28 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:28 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:29 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 02 10:40:29 volumio volumio[718]: info: CURURI: music-library/NAS/MusicCollection/Pink Floyd Nov 02 10:40:29 volumio volumio[718]: info: sendMpdCommand status took 1477 milliseconds Nov 02 10:40:29 volumio volumio[718]: info: sendMpdCommand status took 1477 milliseconds Nov 02 10:40:29 volumio volumio[718]: info: sendMpdCommand status took 1476 milliseconds Nov 02 10:40:29 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:29 volumio volumio[718]: info: Command Router : Notfying DB Updatefalse Nov 02 10:40:29 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:29 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:29 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:29 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:29 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:29 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 02 10:40:29 volumio volumio[718]: info: CURURI: music-library/NAS/MusicCollection/Pink Floyd Nov 02 10:40:29 volumio volumio[718]: info: sendMpdCommand status took 2038 milliseconds Nov 02 10:40:29 volumio volumio[718]: info: sendMpdCommand status took 2039 milliseconds Nov 02 10:40:29 volumio volumio[718]: info: sendMpdCommand status took 2039 milliseconds Nov 02 10:40:29 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:30 volumio volumio[718]: info: Command Router : Notfying DB Updatefalse Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::Close All Modals sent Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::parseState Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 10:40:30 volumio volumio[718]: info: sendMpdCommand playlistinfo took 1141 milliseconds Nov 02 10:40:30 volumio volumio[718]: info: sendMpdCommand playlistinfo took 1141 milliseconds Nov 02 10:40:30 volumio volumio[718]: info: sendMpdCommand playlistinfo took 571 milliseconds Nov 02 10:40:30 volumio volumio[718]: info: sendMpdCommand playlistinfo took 571 milliseconds Nov 02 10:40:30 volumio volumio[718]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 02 10:40:30 volumio volumio[718]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:30 volumio volumio[718]: verbose: ControllerMpd::parseTrackInfo Nov 02 10:40:30 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304232,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:30 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:30 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304232,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:30 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:30 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304324,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:30 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:30 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304324,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:30 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:30 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304507,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:30 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:30 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: ControllerMpd::pushState Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::servicePushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":304507,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Óâåðòþðà. Ïðîëîã.","artist":null,"album":"Ôàóñò CD1","uri":"NAS/MusicCollection/Классика/ФАУСТ/CD 1/01 Фауст CD1 - Увертюра. Пролог..wv","trackType":"wv"} Nov 02 10:40:30 volumio volumio[718]: verbose: CURRENT POSITION 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState stateService play Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::syncState currentStatus play Nov 02 10:40:30 volumio volumio[718]: info: Received an update from plugin. extracting info from payload Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:30 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:30 volumio volumio[718]: info: ------------------------------ 2144ms Nov 02 10:40:30 volumio volumio[718]: info: ------------------------------ 2143ms Nov 02 10:40:30 volumio volumio[718]: info: ------------------------------ 2142ms Nov 02 10:40:30 volumio volumio[718]: info: ------------------------------ 2142ms Nov 02 10:40:30 volumio volumio[718]: info: ------------------------------ 2141ms Nov 02 10:40:30 volumio volumio[718]: info: ------------------------------ 2141ms Nov 02 10:40:30 volumio volumio[718]: info: Preload queue cleared Nov 02 10:40:30 volumio volumio[718]: info: Preload queue cleared Nov 02 10:40:30 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:34 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 10:40:35 volumio volumio[718]: info: Preload queue cleared Nov 02 10:40:35 volumio volumio[718]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 10:40:35 volumio volumio[718]: info: CoreStateMachine::ClearQueue Nov 02 10:40:35 volumio volumio[718]: info: CoreStateMachine::stop Nov 02 10:40:35 volumio volumio[718]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 10:40:35 volumio volumio[718]: info: CoreStateMachine::stPlaybackTimer Nov 02 10:40:35 volumio volumio[718]: info: CoreStateMachine::updateTrackBlock Nov 02 10:40:35 volumio volumio[718]: info: CorePlayQueue::getTrackBlock Nov 02 10:40:35 volumio volumio[718]: info: CoreStateMachine::pushState Nov 02 10:40:35 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:35 volumio volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 10:40:35 volumio volumio[718]: info: CoreCommandRouter::volumioPushState Nov 02 10:40:35 volumio volumio[718]: info: CoreStateMachine::serviceStop Nov 02 10:40:35 volumio volumio[718]: info: CorePlayQueue::getTrack 0 Nov 02 10:40:35 volumio volumio[718]: info: CoreCommandRouter::serviceStop Nov 02 10:40:35 volumio volumio[718]: info: ControllerMpd::stop Nov 02 10:40:35 volumio volumio[718]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 10:40:35 volumio volumio[718]: info: CorePlayQueue::clearPlayQueue Nov 02 10:40:35 volumio volumio[718]: info: CorePlayQueue::saveQueue Nov 02 10:40:35 volumio volumio[718]: info: CoreCommandRouter::volumioPushQueue Nov 02 10:40:35 volumio volumio[718]: info: CoreStateMachine::addQueueItems Nov 02 10:40:35 volumio volumio[718]: info: CorePlayQueue::addQueueItems Nov 02 10:40:35 volumio volumio[718]: info: Preload queue cleared Nov 02 10:40:35 volumio volumio[718]: info: Adding Item to queue: music-library/NAS/MusicCollection/Pink Floyd/Pink Floyd - The Wall (Japan) (1979) Nov 02 10:40:35 volumio volumio[718]: info: Exploding uri music-library/NAS/MusicCollection/Pink Floyd/Pink Floyd - The Wall (Japan) (1979) in service mpd Nov 02 10:40:35 volumio volumio[718]: info: Nov 02 10:40:35 volumio volumio[718]: ---------------------------- MPD announces state update: player Nov 02 10:40:35 volumio volumio[718]: info: ALBUMART /albumart?cacheid=292&web=Pink%20Floyd/The%20Wall/extralarge&path=%2Fmnt%2FNAS%2FMusicCollection%2FPink%20Floyd%2FPink%20Floyd%20-%20The%20Wall%20(Japan)%20(1979)%2FPink%20Floyd%20-%20The%20Wall%20side%2001.dsf&metadata=false Nov 02 10:40:35 volumio volumio[718]: info: URI /mnt/NAS/MusicCollection/Pink Floyd/Pink Floyd - The Wall (Japan) (1979)/Pink Floyd - The Wall side 01.dsf Nov 02 10:40:35 volumio volumio[718]: info: ALBUMART /albumart?cacheid=292&web=Pink%20Floyd/The%20Wall/extralarge&path=%2Fmnt%2FNAS%2FMusicCollection%2FPink%20Floyd%2FPink%20Floyd%20-%20The%20Wall%20(Japan)%20(1979)%2FPink%20Floyd%20-%20The%20Wall%20side%2002.dsf&metadata=false Nov 02 10:40:35 volumio volumio[718]: info: URI /mnt/NAS/MusicCollection/Pink Floyd/Pink Floyd - The Wall (Japan) (1979)/Pink Floyd - The Wall side 02.dsf Nov 02 10:40:35 volumio volumio[718]: info: ALBUMART /albumart?cacheid=292&web=Pink%20Floyd/The%20Wall/extralarge&path=%2Fmnt%2FNAS%2FMusicCollection%2FPink%20Floyd%2FPink%20Floyd%20-%20The%20Wall%20(Japan)%20(1979)%2FPink%20Floyd%20-%20The%20Wall%20side%2003.dsf&metadata=false Nov 02 10:40:35 volumio volumio[718]: info: URI /mnt/NAS/MusicCollection/Pink Floyd/Pink Floyd - The Wall (Japan) (1979)/Pink Floyd - The Wall side 03.dsf Nov 02 10:40:35 volumio volumio[718]: info: ALBUMART /albumart?cacheid=292&web=Pink%20Floyd/The%20Wall/extralarge&path=%2Fmnt%2FNAS%2FMusicCollection%2FPink%20Floyd%2FPink%20Floyd%20-%20The%20Wall%20(Japan)%20(1979)%2FPink%20Floyd%20-%20The%20Wall%20side%2004.dsf&metadata=false Nov 02 10:40:35 volumio volumio[718]: info: URI /mnt/NAS/MusicCollection/Pink Floyd/Pink Floyd - The Wall (Japan) (1979)/Pink Floyd - The Wall side 04.dsf Nov 02 10:40:35 volumio volumio[718]: info: ALBUMART /albumart?cacheid=292&web=Pink%20Floyd/The%20Wall/extralarge&path=%2Fmnt%2FNAS%2FMusicCollection%2FPink%20Floyd%2FPink%20Floyd%20-%20The%20Wall%20(Japan)%20(1979)%2FPink%20Floyd%20-%20The%20Wall.cue&metadata=false Nov 02 10:40:35 volumio volumio[718]: info: URI /mnt/NAS/MusicCollection/Pink Floyd/Pink Floyd - The Wall (Japan) (1979)/Pink Floyd - The Wall.cue Nov 02 10:40:35 volumio volumio[718]: info: ALBUMART /albumart?cacheid=292&web=Pink%20Floyd/The%20Wall/extralarge&path=%2Fmnt%2FNAS%2FMusicCollection%2FPink%20Floyd%2FPink%20Floyd%20-%20The%20Wall%20(Japan)%20(1979)%2FPink%20Floyd%20-%20The%20Wall.cue&metadata=false Nov 02 10:40:35 volumio volumio[718]: info: URI /mnt/NAS/MusicCollection/Pink Floyd/Pink Floyd - The Wall (Japan) (1979)/Pink Floyd - The Wall.cue Nov 02 10:40:35 volumio volumio[718]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 10:40:35 volumio volumio[718]: Error: Unable to resolve or reject the same promise twice Nov 02 10:40:35 volumio volumio[718]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 02 10:40:35 volumio volumio[718]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Nov 02 10:40:35 volumio volumio[718]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Nov 02 10:40:35 volumio volumio[718]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Nov 02 10:40:35 volumio volumio[718]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Nov 02 10:40:35 volumio volumio[718]: at Socket.emit (events.js:315:20) Nov 02 10:40:35 volumio volumio[718]: at addChunk (internal/streams/readable.js:309:12) Nov 02 10:40:35 volumio volumio[718]: at readableAddChunk (internal/streams/readable.js:280:11) Nov 02 10:40:35 volumio volumio[718]: at Socket.Readable.push (internal/streams/readable.js:223:10) Nov 02 10:40:35 volumio volumio[718]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Nov 02 10:40:35 volumio volumio[718]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 10:40:35 volumio sudo[14142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 10:39 Nov 02 10:40:35 volumio sudo[14142]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"