-- Logs begin at Thu 2019-02-14 13:12:00 MSK, end at Sun 2024-08-18 19:28:50 MSK. -- Aug 18 19:27:00 volumio ntpd[780]: Soliciting pool server 2001:67c:380:120::33 Aug 18 19:27:40 volumio ntpd[780]: receive: Unexpected origin timestamp 0xea6c9f57.a28317bb does not match aorg 0000000000.00000000 from server@82.142.168.18 xmt 0xea6c9f7c.9dafaddb Aug 18 19:27:40 volumio ntpd[780]: receive: Unexpected origin timestamp 0xea6c9f57.a2815048 does not match aorg 0000000000.00000000 from server@83.143.51.50 xmt 0xea6c9f7c.a0f34b9c Aug 18 19:27:40 volumio ntpd[780]: receive: Unexpected origin timestamp 0xea6c9f57.a2770ac7 does not match aorg 0000000000.00000000 from server@94.247.111.10 xmt 0xea6c9f7c.a3079880 Aug 18 19:27:42 volumio volumio[919]: info: VolumeController::SetAlsaVolume78 Aug 18 19:27:42 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:42 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:42 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:27:42 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:49 volumio volumio[919]: info: CoreCommandRouter::volumioNext Aug 18 19:27:49 volumio volumio[919]: info: CoreStateMachine::next Aug 18 19:27:49 volumio volumio[919]: info: CoreStateMachine::stop Aug 18 19:27:49 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 19:27:49 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Aug 18 19:27:49 volumio volumio[919]: info: CoreStateMachine::updateTrackBlock Aug 18 19:27:49 volumio volumio[919]: info: CorePlayQueue::getTrackBlock Aug 18 19:27:49 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:49 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:49 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:27:49 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:49 volumio volumio[919]: info: CoreStateMachine::serviceStop Aug 18 19:27:49 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:49 volumio volumio[919]: info: CoreCommandRouter::serviceStop Aug 18 19:27:49 volumio volumio[919]: info: ControllerMpd::stop Aug 18 19:27:49 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Aug 18 19:27:50 volumio volumio[919]: info: Aug 18 19:27:50 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand stop took 65 milliseconds Aug 18 19:27:50 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::play index undefined Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 1 Aug 18 19:27:50 volumio volumio[919]: info: Aug 18 19:27:50 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:50 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:50 volumio volumio[919]: info: Aug 18 19:27:50 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:50 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:50 volumio volumio[919]: info: Aug 18 19:27:50 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:50 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand status took 19 milliseconds Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand status took 8 milliseconds Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand status took 6 milliseconds Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand status took 4 milliseconds Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:50 volumio volumio[919]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 18 19:27:50 volumio volumio[919]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 18 19:27:50 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:27:50 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:50 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::syncState stateService stop Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus stop Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: No code Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:50 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::syncState stateService stop Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus stop Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: No code Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:50 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::syncState stateService stop Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus stop Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: No code Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:50 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::syncState stateService stop Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus stop Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: No code Aug 18 19:27:50 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:50 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:50 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:50 volumio volumio[919]: info: ------------------------------ 281ms Aug 18 19:27:50 volumio volumio[919]: info: ------------------------------ 272ms Aug 18 19:27:50 volumio volumio[919]: info: ------------------------------ 272ms Aug 18 19:27:50 volumio volumio[919]: info: ------------------------------ 271ms Aug 18 19:27:51 volumio volumio[919]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=59.935209412553185&lon=30.256099812206855: Error: Response error: 504 - Gateway Time-out Aug 18 19:27:51 volumio volumio[919]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Aug 18 19:27:51 volumio volumio[919]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 18 19:27:51 volumio volumio[919]: at async Promise.all (index 0) Aug 18 19:27:51 volumio volumio[919]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Aug 18 19:27:51 volumio volumio[919]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Aug 18 19:27:51 volumio volumio[919]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Aug 18 19:27:51 volumio volumio[919]: info: [now-playing] Fetching API key... Aug 18 19:27:51 volumio volumio[919]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=59.935209412553185&lon=30.256099812206855: Error: Response error: 504 - Gateway Time-out Aug 18 19:27:51 volumio volumio[919]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Aug 18 19:27:51 volumio volumio[919]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 18 19:27:51 volumio volumio[919]: at async Promise.all (index 1) Aug 18 19:27:52 volumio volumio[919]: info: [now-playing] OpenWeatherMap API key obtained. Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::volumioPlay Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::play index undefined Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::startPlaybackTimer Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::clearAddPlayTracks USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand stop took 4 milliseconds Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand clear Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces system playlist update Aug 18 19:27:52 volumio volumio[919]: info: Ignoring MPD Status Update Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand clear took 2 milliseconds Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand add "USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf" Aug 18 19:27:52 volumio volumio[919]: error: updateQueue error: null Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces system playlist update Aug 18 19:27:52 volumio volumio[919]: info: Ignoring MPD Status Update Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 5ms Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand add "USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf" took 3 milliseconds Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand play Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces system playlist update Aug 18 19:27:52 volumio volumio[919]: info: Ignoring MPD Status Update Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces system playlist update Aug 18 19:27:52 volumio volumio[919]: info: Ignoring MPD Status Update Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces system playlist update Aug 18 19:27:52 volumio volumio[919]: info: Ignoring MPD Status Update Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 41ms Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand play took 39 milliseconds Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 39ms Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces system playlist update Aug 18 19:27:52 volumio volumio[919]: info: Ignoring MPD Status Update Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces system playlist update Aug 18 19:27:52 volumio volumio[919]: info: Ignoring MPD Status Update Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 64ms Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 33ms Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand status took 28 milliseconds Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 28ms Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand status took 8 milliseconds Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 7ms Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand status took 5 milliseconds Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces system playlist update Aug 18 19:27:52 volumio volumio[919]: info: Ignoring MPD Status Update Aug 18 19:27:52 volumio volumio[919]: info: Aug 18 19:27:52 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand status took 17 milliseconds Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand status took 13 milliseconds Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand status took 12 milliseconds Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:52 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:52 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus stop Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:52 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Aug 18 19:27:52 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:52 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":303,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:52 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Aug 18 19:27:52 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:52 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:52 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:52 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 108ms Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 169ms Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 154ms Aug 18 19:27:52 volumio volumio[919]: info: ------------------------------ 142ms Aug 18 19:27:52 volumio volumio[919]: info: sendMpdCommand status took 140 milliseconds Aug 18 19:27:53 volumio volumio[919]: info: sendMpdCommand playlistinfo took 133 milliseconds Aug 18 19:27:53 volumio volumio[919]: info: sendMpdCommand playlistinfo took 134 milliseconds Aug 18 19:27:53 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:27:53 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:27:53 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:27:53 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:27:53 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":354,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:53 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Aug 18 19:27:53 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:53 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":360,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:53 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Aug 18 19:27:53 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:53 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":360,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:53 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Aug 18 19:27:53 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:53 volumio volumio[919]: info: ------------------------------ 353ms Aug 18 19:27:53 volumio volumio[919]: info: ------------------------------ 347ms Aug 18 19:27:53 volumio volumio[919]: info: ------------------------------ 348ms Aug 18 19:27:53 volumio volumio[919]: info: sendMpdCommand playlistinfo took 285 milliseconds Aug 18 19:27:53 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:27:53 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: verbose: STATE SERVICE {"status":"play","position":0,"seek":360,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:27:53 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::syncState stateService play Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus play Aug 18 19:27:53 volumio volumio[919]: info: Received an update from plugin. extracting info from payload Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:53 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:27:53 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:27:53 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:27:53 volumio volumio[919]: info: ------------------------------ 512ms Aug 18 19:28:05 volumio volumio[919]: info: VolumeController::SetAlsaVolume89 Aug 18 19:28:05 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:05 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:05 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:28:05 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:06 volumio volumio[919]: info: VolumeController::SetAlsaVolume49 Aug 18 19:28:06 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:06 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:06 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:28:06 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:06 volumio volumio[919]: info: VolumeController::SetAlsaVolume31 Aug 18 19:28:06 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:06 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:06 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:28:06 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:11 volumio volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 19:28:12 volumio volumio[919]: info: Getting Alsa Cards List without I2S DAC Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 18 19:28:12 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 18 19:28:14 volumio volumio[919]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 18 19:28:14 volumio volumio[919]: info: Preparing to save Alsa Options, stopping services first Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::volumioGetState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::volumioPause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::servicePause Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::servicePause Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::pause Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand pause Aug 18 19:28:14 volumio volumio[919]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: Setting default mixerSoftMaster for Softvolume device Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 18 19:28:14 volumio volumio[919]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 18 19:28:14 volumio volumio[919]: info: Disabling external Volume Control Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 19:28:14 volumio volumio[919]: info: Enable softmixer device for audio device undefined Aug 18 19:28:14 volumio volumio[919]: info: Aug 18 19:28:14 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand pause took 316 milliseconds Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:28:14 volumio volumio[919]: info: Aug 18 19:28:14 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:28:14 volumio volumio[919]: info: Aug 18 19:28:14 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:28:14 volumio volumio[919]: info: Aug 18 19:28:14 volumio volumio[919]: ---------------------------- MPD announces state update: player Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::getState Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand status Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand status took 14 milliseconds Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand status took 12 milliseconds Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand status took 9 milliseconds Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand status took 6 milliseconds Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::parseState Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:14 volumio volumio[919]: info: Software Volume ALSA configuration written Aug 18 19:28:14 volumio volumio[919]: info: Preparing to generate the ALSA configuration file Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand playlistinfo took 144 milliseconds Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand playlistinfo took 144 milliseconds Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand playlistinfo took 144 milliseconds Aug 18 19:28:14 volumio volumio[919]: info: sendMpdCommand playlistinfo took 144 milliseconds Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:28:14 volumio volumio[919]: verbose: ControllerMpd::parseTrackInfo Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21913,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:28:14 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::syncState stateService pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21913,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:28:14 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::syncState stateService pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21913,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:28:14 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::syncState stateService pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Aug 18 19:28:14 volumio volumio[919]: info: ControllerMpd::pushState Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::servicePushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":21913,"duration":153,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Paixao_The Eternal Rain - excerpt (from FMICA990011901)","artist":"Various Artists","album":"Welcome to NativeDSD Sampler","uri":"USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf","trackType":"dsf"} Aug 18 19:28:14 volumio volumio[919]: verbose: CURRENT POSITION 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::syncState stateService pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::syncState currentStatus pause Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:14 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:14 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Aug 18 19:28:14 volumio volumio[919]: info: ------------------------------ 282ms Aug 18 19:28:14 volumio volumio[919]: info: ------------------------------ 280ms Aug 18 19:28:14 volumio volumio[919]: info: ------------------------------ 278ms Aug 18 19:28:14 volumio volumio[919]: info: ------------------------------ 276ms Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 19:28:14 volumio volumio[919]: info: Getting Alsa Cards List without I2S DAC Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 18 19:28:14 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 18 19:28:14 volumio volumio[919]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 18 19:28:14 volumio volumio[919]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 18 19:28:14 volumio volumio[919]: info: Reading ALSA contributions from plugins. Aug 18 19:28:15 volumio volumio[919]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 18 19:28:15 volumio volumio[919]: info: Asound.conf file unchanged, so no further update is needed Aug 18 19:28:15 volumio volumio[919]: info: Output device has changed, restarting MPD Aug 18 19:28:15 volumio volumio[919]: info: Output device has changed, restarting Shairport Sync Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:15 volumio sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 19:28:15 volumio sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 19:28:15 volumio sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 19:28:15 volumio sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 19:28:15 volumio sudo[1890]: pam_unix(sudo:session): session closed for user root Aug 18 19:28:15 volumio volumio[919]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 19:28:15 volumio systemd[1]: Stopping Music Player Daemon... Aug 18 19:28:15 volumio volumio[919]: error: Upnp client error: Error: This socket has been ended by the other party Aug 18 19:28:15 volumio volumio[919]: info: MPD Permissions set Aug 18 19:28:15 volumio volumio[919]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 18 19:28:15 volumio volumio[919]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: Starting Shairport Sync Aug 18 19:28:15 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 18 19:28:15 volumio sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 19:28:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 18 19:28:15 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 18 19:28:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 18 19:28:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 18 19:28:15 volumio sudo[1909]: pam_unix(sudo:session): session closed for user root Aug 18 19:28:15 volumio volumio[919]: info: Shairport-Sync Started Aug 18 19:28:15 volumio systemd[1]: mpd.service: Succeeded. Aug 18 19:28:15 volumio systemd[1]: Stopped Music Player Daemon. Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 19:28:15 volumio systemd[1]: Starting Music Player Daemon... Aug 18 19:28:15 volumio sudo[1914]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 19:28:15 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 19:28:15 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root Aug 18 19:28:17 volumio mpd[1919]: Aug 18 19:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 18 19:28:17 volumio systemd[1]: Started Music Player Daemon. Aug 18 19:28:17 volumio sudo[1892]: pam_unix(sudo:session): session closed for user root Aug 18 19:28:17 volumio volumio[919]: error: updateQueue error: null Aug 18 19:28:18 volumio volumio[919]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 18 19:28:18 volumio volumio[919]: info: CoreStateMachine::getcurrentVolume Aug 18 19:28:18 volumio volumio[919]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 19:28:18 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:18 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:18 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:18 volumio volumio[919]: info: CoreStateMachine::updateTrackBlock Aug 18 19:28:18 volumio volumio[919]: info: CorePlayQueue::getTrackBlock Aug 18 19:28:18 volumio volumio[919]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 19:28:18 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:18 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:18 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:19 volumio volumio[919]: error: Upnp client error: Error: This socket has been ended by the other party Aug 18 19:28:29 volumio volumio[919]: info: VolumeController::SetAlsaVolume49 Aug 18 19:28:29 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:29 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:29 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:28:29 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:29 volumio volumio[919]: info: VolumeController::SetAlsaVolume49 Aug 18 19:28:29 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:29 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:29 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:30 volumio volumio[919]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/onecall?appid=5796abbde9106b7da4febfae8c44c232&lat=59.935209412553185&lon=30.256099812206855: Error: Response error: 401 - Unauthorized Aug 18 19:28:30 volumio volumio[919]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Aug 18 19:28:30 volumio volumio[919]: at runMicrotasks () Aug 18 19:28:30 volumio volumio[919]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 18 19:28:30 volumio volumio[919]: at async Promise.all (index 0) Aug 18 19:28:30 volumio volumio[919]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Aug 18 19:28:30 volumio volumio[919]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Aug 18 19:28:30 volumio volumio[919]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Aug 18 19:28:30 volumio volumio[919]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Response error: 401 - Unauthorized Aug 18 19:28:30 volumio volumio[919]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:114:19) Aug 18 19:28:30 volumio volumio[919]: at runMicrotasks () Aug 18 19:28:30 volumio volumio[919]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 18 19:28:30 volumio volumio[919]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Aug 18 19:28:30 volumio volumio[919]: (node:919) UnhandledPromiseRejectionWarning: Error: Response error: 401 - Unauthorized Aug 18 19:28:30 volumio volumio[919]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Aug 18 19:28:30 volumio volumio[919]: at runMicrotasks () Aug 18 19:28:30 volumio volumio[919]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 18 19:28:30 volumio volumio[919]: at async Promise.all (index 0) Aug 18 19:28:30 volumio volumio[919]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:84:24) Aug 18 19:28:30 volumio volumio[919]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:97:45) Aug 18 19:28:30 volumio volumio[919]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:297:25 Aug 18 19:28:30 volumio volumio[919]: (Use `node --trace-warnings ...` to show where the warning was created) Aug 18 19:28:30 volumio volumio[919]: (node:919) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Aug 18 19:28:30 volumio volumio[919]: (node:919) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Aug 18 19:28:30 volumio volumio[919]: error: [now-playing] Error fetching https://openweathermap.org/data/2.5/weather?appid=5796abbde9106b7da4febfae8c44c232&lat=59.935209412553185&lon=30.256099812206855: Error: Response error: 401 - Unauthorized Aug 18 19:28:30 volumio volumio[919]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:29:15) Aug 18 19:28:30 volumio volumio[919]: at runMicrotasks () Aug 18 19:28:30 volumio volumio[919]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 18 19:28:30 volumio volumio[919]: at async Promise.all (index 1) Aug 18 19:28:40 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 18 19:28:40 volumio volumio[919]: info: CURURI: music-library Aug 18 19:28:41 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 18 19:28:41 volumio volumio[919]: info: CURURI: music-library/USB Aug 18 19:28:42 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 18 19:28:42 volumio volumio[919]: info: CURURI: music-library/USB/SONY_USB Aug 18 19:28:47 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 18 19:28:47 volumio volumio[919]: info: CURURI: music-library/USB/SONY_USB/DSD Aug 18 19:28:49 volumio volumio[919]: info: Preload queue cleared Aug 18 19:28:49 volumio volumio[919]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 18 19:28:49 volumio volumio[919]: info: CoreStateMachine::ClearQueue Aug 18 19:28:49 volumio volumio[919]: info: CoreStateMachine::stop Aug 18 19:28:49 volumio volumio[919]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 19:28:49 volumio volumio[919]: info: CoreStateMachine::updateTrackBlock Aug 18 19:28:49 volumio volumio[919]: info: CorePlayQueue::getTrackBlock Aug 18 19:28:49 volumio volumio[919]: info: CoreStateMachine::stPlaybackTimer Aug 18 19:28:49 volumio volumio[919]: info: CoreStateMachine::pushState Aug 18 19:28:49 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:49 volumio volumio[919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 19:28:49 volumio volumio[919]: info: CoreCommandRouter::volumioPushState Aug 18 19:28:49 volumio volumio[919]: info: CoreStateMachine::serviceStop Aug 18 19:28:49 volumio volumio[919]: info: CorePlayQueue::getTrack 0 Aug 18 19:28:49 volumio volumio[919]: info: CoreCommandRouter::serviceStop Aug 18 19:28:49 volumio volumio[919]: info: ControllerMpd::stop Aug 18 19:28:49 volumio volumio[919]: verbose: ControllerMpd::sendMpdCommand stop Aug 18 19:28:49 volumio volumio[919]: info: CorePlayQueue::clearPlayQueue Aug 18 19:28:49 volumio volumio[919]: info: CorePlayQueue::saveQueue Aug 18 19:28:49 volumio volumio[919]: info: CoreCommandRouter::volumioPushQueue Aug 18 19:28:49 volumio volumio[919]: info: CoreStateMachine::addQueueItems Aug 18 19:28:49 volumio volumio[919]: info: CorePlayQueue::addQueueItems Aug 18 19:28:49 volumio volumio[919]: info: Preload queue cleared Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/1_Introduction-and-Classical-Choices_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/1_Introduction-and-Classical-Choices_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/2_Beethoven_Symphony-no-5-Allegro-con-Brio-excerpt-from-FR718SACD_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/2_Beethoven_Symphony-no-5-Allegro-con-Brio-excerpt-from-FR718SACD_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/3_Chopin_Etude-in-F-major-op-10-excerpt-from-EUDDR1703_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/3_Chopin_Etude-in-F-major-op-10-excerpt-from-EUDDR1703_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/4_Mendelssohn_String-Quartet-no-2-in-a-minor-Intermezzo-excerpt-from-39317_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/4_Mendelssohn_String-Quartet-no-2-in-a-minor-Intermezzo-excerpt-from-39317_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/5_Shostakovich_Symphony-no-5-in-D-minor-allegretto-excerpt-from-FR724SACD_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/5_Shostakovich_Symphony-no-5-in-D-minor-allegretto-excerpt-from-FR724SACD_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/6_Barrios_La-Catedral-Preludio-excerpt-from-EUDDR1801_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/6_Barrios_La-Catedral-Preludio-excerpt-from-EUDDR1801_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/7_Bach_Suite-No-1-I-Prelude-excerpt-from-NC15003_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/7_Bach_Suite-No-1-I-Prelude-excerpt-from-NC15003_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/8_Nordheim_Music-to-Two-Fragments-by-Shelley-II-No-Music-from-LWC1134_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/8_Nordheim_Music-to-Two-Fragments-by-Shelley-II-No-Music-from-LWC1134_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/9_Traditional_Hijaz-Improvisation-excerpt-from-TTK0008_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/9_Traditional_Hijaz-Improvisation-excerpt-from-TTK0008_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/10_Mahler_Symphony-no-3-Langsam-excerpt-from-38817_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/10_Mahler_Symphony-no-3-Langsam-excerpt-from-38817_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/11_Introduction-to-Pop-Jazz-Rock-Tracks_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/11_Introduction-to-Pop-Jazz-Rock-Tracks_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/14_Skidmore_The-Impossible-Sum-excerpt-from-TTK0015_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/14_Skidmore_The-Impossible-Sum-excerpt-from-TTK0015_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/15_Fain_Secret-Love-excerpt-from-YAR65006DSD_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/15_Fain_Secret-Love-excerpt-from-YAR65006DSD_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/17_Newman_Short-People-from-JL002_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/17_Newman_Short-People-from-JL002_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Using cached record of: music-library/USB/SONY_USB/DSD/18_Paixao_The-Eternal-Rain-excerpt-from-FMICA990011901_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/19_Rodgers_The-Sweetest-Sounds-from-JL009_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/19_Rodgers_The-Sweetest-Sounds-from-JL009_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/20_Kohashi_Blues-for-Maya-excerpt-from-TTK0010_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/20_Kohashi_Blues-for-Maya-excerpt-from-TTK0010_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/21_Previn_Youre-Gonna-Hear-from-Me-from-2XHDRE1069_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/21_Previn_Youre-Gonna-Hear-from-Me-from-2XHDRE1069_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/22_Closing-remarks_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/22_Closing-remarks_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/23_Vivaldi-The-Four-Seasons-Winter-II-Largo-from-40318_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/23_Vivaldi-The-Four-Seasons-Winter-II-Largo-from-40318_stereo.dsf in service mpd Aug 18 19:28:49 volumio volumio[919]: info: Adding Item to queue: music-library/USB/SONY_USB/DSD/95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip Aug 18 19:28:49 volumio volumio[919]: info: Exploding uri music-library/USB/SONY_USB/DSD/95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip in service mpd Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F1_Introduction-and-Classical-Choices_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/1_Introduction-and-Classical-Choices_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F2_Beethoven_Symphony-no-5-Allegro-con-Brio-excerpt-from-FR718SACD_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/2_Beethoven_Symphony-no-5-Allegro-con-Brio-excerpt-from-FR718SACD_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F3_Chopin_Etude-in-F-major-op-10-excerpt-from-EUDDR1703_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/3_Chopin_Etude-in-F-major-op-10-excerpt-from-EUDDR1703_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F4_Mendelssohn_String-Quartet-no-2-in-a-minor-Intermezzo-excerpt-from-39317_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/4_Mendelssohn_String-Quartet-no-2-in-a-minor-Intermezzo-excerpt-from-39317_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F5_Shostakovich_Symphony-no-5-in-D-minor-allegretto-excerpt-from-FR724SACD_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/5_Shostakovich_Symphony-no-5-in-D-minor-allegretto-excerpt-from-FR724SACD_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F6_Barrios_La-Catedral-Preludio-excerpt-from-EUDDR1801_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/6_Barrios_La-Catedral-Preludio-excerpt-from-EUDDR1801_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F7_Bach_Suite-No-1-I-Prelude-excerpt-from-NC15003_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/7_Bach_Suite-No-1-I-Prelude-excerpt-from-NC15003_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F8_Nordheim_Music-to-Two-Fragments-by-Shelley-II-No-Music-from-LWC1134_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/8_Nordheim_Music-to-Two-Fragments-by-Shelley-II-No-Music-from-LWC1134_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F9_Traditional_Hijaz-Improvisation-excerpt-from-TTK0008_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/9_Traditional_Hijaz-Improvisation-excerpt-from-TTK0008_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F10_Mahler_Symphony-no-3-Langsam-excerpt-from-38817_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/10_Mahler_Symphony-no-3-Langsam-excerpt-from-38817_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F11_Introduction-to-Pop-Jazz-Rock-Tracks_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/11_Introduction-to-Pop-Jazz-Rock-Tracks_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/12_Charles_A-Fool-For-You-excerpt-from-SL1023A_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/13_Polence_Counting-Frogs-excerpt-from-JL003_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F14_Skidmore_The-Impossible-Sum-excerpt-from-TTK0015_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/14_Skidmore_The-Impossible-Sum-excerpt-from-TTK0015_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F15_Fain_Secret-Love-excerpt-from-YAR65006DSD_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/15_Fain_Secret-Love-excerpt-from-YAR65006DSD_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F16_Ryan_Firefly-from-VDM03855032_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/16_Ryan_Firefly-from-VDM03855032_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F17_Newman_Short-People-from-JL002_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/17_Newman_Short-People-from-JL002_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F19_Rodgers_The-Sweetest-Sounds-from-JL009_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/19_Rodgers_The-Sweetest-Sounds-from-JL009_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F20_Kohashi_Blues-for-Maya-excerpt-from-TTK0010_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/20_Kohashi_Blues-for-Maya-excerpt-from-TTK0010_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F21_Previn_Youre-Gonna-Hear-from-Me-from-2XHDRE1069_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/21_Previn_Youre-Gonna-Hear-from-Me-from-2XHDRE1069_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F22_Closing-remarks_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/22_Closing-remarks_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=Various%20Artists/Welcome%20to%20NativeDSD%20Sampler/extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F23_Vivaldi-The-Four-Seasons-Winter-II-Largo-from-40318_stereo.dsf&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/23_Vivaldi-The-Four-Seasons-Winter-II-Largo-from-40318_stereo.dsf Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=//extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip Aug 18 19:28:49 volumio volumio[919]: info: ALBUMART /albumart?cacheid=635&web=//extralarge&path=%2Fmnt%2FUSB%2FSONY_USB%2FDSD%2F95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip&metadata=false Aug 18 19:28:49 volumio volumio[919]: info: URI /mnt/USB/SONY_USB/DSD/95745-NDSD014-welcome-to-nativedsd-sampler-dsd-64-free-stereo.zip Aug 18 19:28:49 volumio volumio[919]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 19:28:49 volumio volumio[919]: Error: Unable to resolve or reject the same promise twice Aug 18 19:28:49 volumio volumio[919]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 18 19:28:49 volumio volumio[919]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Aug 18 19:28:49 volumio volumio[919]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 18 19:28:49 volumio volumio[919]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 18 19:28:49 volumio volumio[919]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 18 19:28:49 volumio volumio[919]: at Socket.emit (events.js:315:20) Aug 18 19:28:49 volumio volumio[919]: at addChunk (internal/streams/readable.js:309:12) Aug 18 19:28:49 volumio volumio[919]: at readableAddChunk (internal/streams/readable.js:280:11) Aug 18 19:28:49 volumio volumio[919]: at Socket.Readable.push (internal/streams/readable.js:223:10) Aug 18 19:28:49 volumio volumio[919]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 18 19:28:49 volumio volumio[919]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 19:28:50 volumio sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 19:27 Aug 18 19:28:50 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"