-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Sat 2024-11-23 11:20:55 MSK. -- Nov 23 11:19:00 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:04 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:05 volumio ntpd[687]: Soliciting pool server 185.209.85.222 Nov 23 11:19:07 volumio volumio[927]: info: Enabling MyMusic plugin bluetooth Nov 23 11:19:07 volumio volumio[927]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Nov 23 11:19:07 volumio volumio[927]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Nov 23 11:19:07 volumio volumio[927]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Nov 23 11:19:07 volumio volumio[927]: error: Could not Enable MyMusic Plugin: Error Nov 23 11:19:08 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:10 volumio ntpd[687]: Soliciting pool server 185.209.85.222 Nov 23 11:19:12 volumio volumio[927]: info: MPD Database updated - AlbumList cache refreshed Nov 23 11:19:12 volumio volumio[927]: info: Nov 23 11:19:12 volumio volumio[927]: ---------------------------- MPD announces state update: database Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: Nov 23 11:19:12 volumio volumio[927]: ---------------------------- MPD announces state update: update Nov 23 11:19:12 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: MPD Database updated - AlbumList cache refreshed Nov 23 11:19:12 volumio volumio[927]: info: Nov 23 11:19:12 volumio volumio[927]: ---------------------------- MPD announces state update: database Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: Nov 23 11:19:12 volumio volumio[927]: ---------------------------- MPD announces state update: update Nov 23 11:19:12 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: MPD Database updated - AlbumList cache refreshed Nov 23 11:19:12 volumio volumio[927]: info: Nov 23 11:19:12 volumio volumio[927]: ---------------------------- MPD announces state update: database Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: Nov 23 11:19:12 volumio volumio[927]: ---------------------------- MPD announces state update: update Nov 23 11:19:12 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:12 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:12 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:13 volumio volumio[927]: info: sendMpdCommand status took 432 milliseconds Nov 23 11:19:13 volumio volumio[927]: info: sendMpdCommand status took 431 milliseconds Nov 23 11:19:13 volumio volumio[927]: info: sendMpdCommand status took 430 milliseconds Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: info: Command Router : Notfying DB Updatefalse Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::Close All Modals sent Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: 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} Nov 23 11:19:13 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState stateService stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: No code Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: 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} Nov 23 11:19:13 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState stateService stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: No code Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: ------------------------------ 536ms Nov 23 11:19:13 volumio volumio[927]: info: ------------------------------ 537ms Nov 23 11:19:13 volumio volumio[927]: info: sendMpdCommand status took 791 milliseconds Nov 23 11:19:13 volumio volumio[927]: info: sendMpdCommand status took 790 milliseconds Nov 23 11:19:13 volumio volumio[927]: info: sendMpdCommand status took 791 milliseconds Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: info: Command Router : Notfying DB Updatefalse Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::Close All Modals sent Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: 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} Nov 23 11:19:13 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState stateService stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: No code Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: 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} Nov 23 11:19:13 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState stateService stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: No code Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: ------------------------------ 877ms Nov 23 11:19:13 volumio volumio[927]: info: ------------------------------ 877ms Nov 23 11:19:13 volumio volumio[927]: info: sendMpdCommand status took 1137 milliseconds Nov 23 11:19:13 volumio volumio[927]: info: sendMpdCommand status took 1136 milliseconds Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: info: Command Router : Notfying DB Updatefalse Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::Close All Modals sent Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:13 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: 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} Nov 23 11:19:13 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState stateService stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: No code Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: 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} Nov 23 11:19:13 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState stateService stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:13 volumio volumio[927]: info: No code Nov 23 11:19:13 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:13 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:13 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:14 volumio volumio[927]: info: ------------------------------ 1224ms Nov 23 11:19:14 volumio volumio[927]: info: ------------------------------ 1224ms Nov 23 11:19:16 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:20 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:24 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:28 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:32 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:36 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 11:19:45 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 23 11:19:45 volumio volumio[927]: info: CURURI: music-library Nov 23 11:19:45 volumio volumio[927]: info: Preload queue cleared Nov 23 11:19:46 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 23 11:19:46 volumio volumio[927]: info: CURURI: music-library/USB Nov 23 11:19:46 volumio volumio[927]: info: Preload queue cleared Nov 23 11:19:46 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 23 11:19:46 volumio volumio[927]: info: CURURI: music-library/USB/ESD-USB Nov 23 11:19:46 volumio volumio[927]: info: Preload queue cleared Nov 23 11:19:47 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 23 11:19:47 volumio volumio[927]: info: CURURI: music-library/USB/ESD-USB/Ford music Nov 23 11:19:47 volumio volumio[927]: info: Preload queue cleared Nov 23 11:19:48 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 23 11:19:48 volumio volumio[927]: info: CURURI: music-library/USB/ESD-USB/Ford music/A-HA Nov 23 11:19:48 volumio volumio[927]: info: Preload queue cleared Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/12. The Weight Of The Wind.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/02. Hunting High And Low (7'' Remix).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/03. Stay On These Roads.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/04. Foot Of The Mountain.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/05. The Sun Always Shines On T.V..mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/06. I've Been Losing You.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/07. Summer Moved On.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/08. Crying In The Rain.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/09. Analogue.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/10. Manhattan Skyline (Edit Version).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/12. Scoundrel Days.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/13. Rolling Thunder.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/14. And You Tell Me.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/15. You Are The One (7'' Remix).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/16. The Blue Sky.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/17. The Living Daylights.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/18. Lifelines.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/19. Living A Boy's Adventure Tale.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/02. Angel In The Snow (Edit).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/04. Train Of Thought (7'' Remix).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/07. Waiting For Her.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/08. Here I Stand And Face The Rain.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/10. Minor Earth Major Sky (Niven's Radio Edit).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/13. Slender Frame.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/14. Move To Memphis (Single Version).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/15. Touchy! (UK DJ Edit).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/16. I Call Your Name.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/18. Butterfly, Butterfly (The Last Hurrah).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Preloading song: music-library/USB/ESD-USB/Ford music/A-HA/11. Cry Wolf.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/12. The Weight Of The Wind.mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=//extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F12.%20The%20Weight%20Of%20The%20Wind.mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/12. The Weight Of The Wind.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F01.%20Take%20On%20Me.mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/02. Hunting High And Low (7'' Remix).mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F02.%20Hunting%20High%20And%20Low%20(7''%20Remix).mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/02. Hunting High And Low (7'' Remix).mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/03. Stay On These Roads.mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F03.%20Stay%20On%20These%20Roads.mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/03. Stay On These Roads.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/04. Foot Of The Mountain.mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F04.%20Foot%20Of%20The%20Mountain.mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/04. Foot Of The Mountain.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/05. The Sun Always Shines On T.V..mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F05.%20The%20Sun%20Always%20Shines%20On%20T.V..mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/05. The Sun Always Shines On T.V..mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/06. I've Been Losing You.mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F06.%20I've%20Been%20Losing%20You.mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/06. I've Been Losing You.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/07. Summer Moved On.mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F07.%20Summer%20Moved%20On.mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/07. Summer Moved On.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/08. Crying In The Rain.mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F08.%20Crying%20In%20The%20Rain.mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/08. Crying In The Rain.mp3 Nov 23 11:19:48 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/09. Analogue.mp3 in service mpd Nov 23 11:19:48 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F09.%20Analogue.mp3&metadata=false Nov 23 11:19:48 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/09. Analogue.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/10. Manhattan Skyline (Edit Version).mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F10.%20Manhattan%20Skyline%20(Edit%20Version).mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/10. Manhattan Skyline (Edit Version).mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/12. Scoundrel Days.mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F12.%20Scoundrel%20Days.mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/12. Scoundrel Days.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/13. Rolling Thunder.mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F13.%20Rolling%20Thunder.mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/13. Rolling Thunder.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/14. And You Tell Me.mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F14.%20And%20You%20Tell%20Me.mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/14. And You Tell Me.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/15. You Are The One (7'' Remix).mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F15.%20You%20Are%20The%20One%20(7''%20Remix).mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/15. You Are The One (7'' Remix).mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/16. The Blue Sky.mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F16.%20The%20Blue%20Sky.mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/16. The Blue Sky.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/17. The Living Daylights.mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F17.%20The%20Living%20Daylights.mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/17. The Living Daylights.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/18. Lifelines.mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F18.%20Lifelines.mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/18. Lifelines.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/19. Living A Boy's Adventure Tale.mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20%20Disc%201%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F19.%20Living%20A%20Boy's%20Adventure%20Tale.mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/19. Living A Boy's Adventure Tale.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Exploding uri music-library/USB/ESD-USB/Ford music/A-HA/02. Angel In The Snow (Edit).mp3 in service mpd Nov 23 11:19:49 volumio volumio[927]: info: ALBUMART /albumart?cacheid=917&web=A-ha/25%20-%20Disc%202%20(Japanese%20Version)/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2FFord%20music%2FA-HA%2F02.%20Angel%20In%20The%20Snow%20(Edit).mp3&metadata=false Nov 23 11:19:49 volumio volumio[927]: info: URI /mnt/USB/ESD-USB/Ford music/A-HA/02. Angel In The Snow (Edit).mp3 Nov 23 11:19:49 volumio volumio[927]: info: Preload queue cleared Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioAddQueueItems Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::addQueueItems Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::addQueueItems Nov 23 11:19:49 volumio volumio[927]: info: Preload queue cleared Nov 23 11:19:49 volumio volumio[927]: info: Adding Item to queue: music-library/USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3 Nov 23 11:19:49 volumio volumio[927]: info: Using cached record of: music-library/USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushQueue Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::saveQueue Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::updateTrackBlock Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrackBlock Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPlay Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::play index 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::stop Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::play index undefined Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::startPlaybackTimer Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3 Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand stop took 3 milliseconds Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces system playlist update Nov 23 11:19:49 volumio volumio[927]: info: Ignoring MPD Status Update Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand clear took 2 milliseconds Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3" Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces system playlist update Nov 23 11:19:49 volumio volumio[927]: info: Ignoring MPD Status Update Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces system playlist update Nov 23 11:19:49 volumio volumio[927]: info: Ignoring MPD Status Update Nov 23 11:19:49 volumio volumio[927]: error: updateQueue error: null Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces system playlist update Nov 23 11:19:49 volumio volumio[927]: info: Ignoring MPD Status Update Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 9ms Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand add "USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3" took 8 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 7ms Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 5ms Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand play Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces system playlist update Nov 23 11:19:49 volumio volumio[927]: info: Ignoring MPD Status Update Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces system playlist update Nov 23 11:19:49 volumio volumio[927]: info: Ignoring MPD Status Update Nov 23 11:19:49 volumio volumio[927]: error: Upnp client error: Error: This socket has been ended by the other party Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 9ms Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand play took 7 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 6ms Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 5ms Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand status took 42 milliseconds Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand status took 44 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand status took 42 milliseconds Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:49 volumio volumio[927]: info: Nov 23 11:19:49 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand status took 9 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand status took 5 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand status took 4 milliseconds Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:19:49 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus stop Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:19:49 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Nov 23 11:19:49 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:19:49 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Nov 23 11:19:49 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 89ms Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 114ms Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 113ms Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand playlistinfo took 58 milliseconds Nov 23 11:19:49 volumio volumio[927]: info: sendMpdCommand playlistinfo took 57 milliseconds Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Nov 23 11:19:49 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:19:49 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Nov 23 11:19:49 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:19:49 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Nov 23 11:19:49 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:19:49 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState stateService play Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus play Nov 23 11:19:49 volumio volumio[927]: info: Received an update from plugin. extracting info from payload Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:19:49 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:19:49 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 144ms Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 141ms Nov 23 11:19:49 volumio volumio[927]: info: ------------------------------ 140ms Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::volumioPause Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::pause Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::stPlaybackTimer Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::servicePause Nov 23 11:20:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::servicePause Nov 23 11:20:01 volumio volumio[927]: info: ControllerMpd::pause Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand pause Nov 23 11:20:01 volumio volumio[927]: info: sendMpdCommand pause took 1 milliseconds Nov 23 11:20:01 volumio volumio[927]: info: Nov 23 11:20:01 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:20:01 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:20:01 volumio volumio[927]: info: Nov 23 11:20:01 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:20:01 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:20:01 volumio volumio[927]: info: Nov 23 11:20:01 volumio volumio[927]: ---------------------------- MPD announces state update: player Nov 23 11:20:01 volumio volumio[927]: info: ControllerMpd::getState Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand status Nov 23 11:20:01 volumio volumio[927]: info: sendMpdCommand status took 5 milliseconds Nov 23 11:20:01 volumio volumio[927]: info: sendMpdCommand status took 3 milliseconds Nov 23 11:20:01 volumio volumio[927]: info: sendMpdCommand status took 2 milliseconds Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::parseState Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 11:20:01 volumio volumio[927]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 11:20:01 volumio volumio[927]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 11:20:01 volumio volumio[927]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Nov 23 11:20:01 volumio volumio[927]: verbose: ControllerMpd::parseTrackInfo Nov 23 11:20:01 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:20:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:01 volumio volumio[927]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13085,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:20:01 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::syncState stateService pause Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus pause Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:20:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::stPlaybackTimer Nov 23 11:20:01 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:20:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:01 volumio volumio[927]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13085,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:20:01 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::syncState stateService pause Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus pause Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:20:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::stPlaybackTimer Nov 23 11:20:01 volumio volumio[927]: info: ControllerMpd::pushState Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::servicePushState Nov 23 11:20:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:01 volumio volumio[927]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13085,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Take On Me","artist":"A-ha","album":"25 - Disc 1 (Japanese Version)","uri":"USB/ESD-USB/Ford music/A-HA/01. Take On Me.mp3","trackType":"mp3"} Nov 23 11:20:01 volumio volumio[927]: verbose: CURRENT POSITION 0 Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::syncState stateService pause Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::syncState currentStatus pause Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::pushState Nov 23 11:20:01 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:01 volumio volumio[927]: info: CoreCommandRouter::volumioPushState Nov 23 11:20:01 volumio volumio[927]: info: CoreStateMachine::stPlaybackTimer Nov 23 11:20:01 volumio volumio[927]: info: ------------------------------ 53ms Nov 23 11:20:01 volumio volumio[927]: info: ------------------------------ 51ms Nov 23 11:20:01 volumio volumio[927]: info: ------------------------------ 51ms Nov 23 11:20:11 volumio ntpd[687]: Soliciting pool server 2a03:aa00::136:55 Nov 23 11:20:16 volumio ntpd[687]: Soliciting pool server 188.227.94.15 Nov 23 11:20:16 volumio ntpd[687]: Soliciting pool server 188.227.94.15 Nov 23 11:20:17 volumio ntpd[687]: Soliciting pool server 188.227.94.15 Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 11:20:41 volumio volumio[927]: info: Discovery: Getting this device information Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Nov 23 11:20:41 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 11:20:41 volumio volumio[927]: info: Discovery: Getting this device information Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Nov 23 11:20:41 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 11:20:41 volumio volumio[927]: info: Discovery: Getting this device information Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Nov 23 11:20:41 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:41 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 11:20:42 volumio volumio[927]: verbose: New Socket.io Connection to 192.168.0.105:3000 from 192.168.0.106 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Nov 23 11:20:50 volumio volumio[927]: verbose: New Socket.io Connection to 192.168.0.105 from 192.168.0.106 UA: Mozilla/5.0 (Linux; Android 7.0; Meizu M6s Build/NRD90M; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/119.0.6045.193 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Nov 23 11:20:50 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::volumioGetQueue Nov 23 11:20:50 volumio volumio[927]: info: CoreStateMachine::getQueue Nov 23 11:20:50 volumio volumio[927]: info: CorePlayQueue::getQueue Nov 23 11:20:50 volumio volumio[927]: info: Listing playlists Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 11:20:50 volumio volumio[927]: info: Received Get System Info Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 11:20:50 volumio volumio[927]: info: Discovery: Getting this device information Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Nov 23 11:20:50 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 11:20:50 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Nov 23 11:20:50 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:51 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 23 11:20:52 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 23 11:20:52 volumio volumio[927]: info: Received Get System Info Nov 23 11:20:52 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 11:20:52 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 11:20:52 volumio volumio[927]: info: Discovery: Getting this device information Nov 23 11:20:52 volumio volumio[927]: info: CoreCommandRouter::volumioGetState Nov 23 11:20:52 volumio volumio[927]: info: CorePlayQueue::getTrack 0 Nov 23 11:20:52 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 11:20:55 volumio volumio[927]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 23 11:20:55 volumio volumio[927]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 11:20:55 volumio volumio[927]: TypeError: Cannot read property 'length' of undefined Nov 23 11:20:55 volumio volumio[927]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 23 11:20:55 volumio volumio[927]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 23 11:20:55 volumio volumio[927]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 23 11:20:55 volumio volumio[927]: at Parser.emit (events.js:315:20) Nov 23 11:20:55 volumio volumio[927]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 23 11:20:55 volumio volumio[927]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 23 11:20:55 volumio volumio[927]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 23 11:20:55 volumio volumio[927]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 23 11:20:55 volumio volumio[927]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 23 11:20:55 volumio volumio[927]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 23 11:20:55 volumio volumio[927]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 23 11:20:55 volumio volumio[927]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 23 11:20:55 volumio volumio[927]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 23 11:20:55 volumio volumio[927]: at IncomingMessage.emit (events.js:327:22) Nov 23 11:20:55 volumio volumio[927]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 23 11:20:55 volumio volumio[927]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 23 11:20:55 volumio volumio[927]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 11:20:55 volumio sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 11:19 Nov 23 11:20:55 volumio sudo[1863]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"