-- Logs begin at Sat 2024-10-19 18:27:44 MSK, end at Sat 2024-10-19 19:13:26 MSK. -- Oct 19 19:12:13 volumio volumio[5879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 19 19:12:13 volumio volumio[5879]: info: CoreCommandRouter::volumioGetState Oct 19 19:12:13 volumio volumio[5879]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%D0%99%D0%BE%D1%80%D1%88/%D0%A1%D1%87%D0%B0%D1%81%D1%82%D1%8C%D0%B5!%20%D0%9A%D0%BE%D0%BD%D0%B5%D1%86/2f6f1618-9603-4fcf-a4c8-08ca8f34e647.jpg' Oct 19 19:12:14 volumio volumio[5879]: info: Oct 19 19:12:14 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:14 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:14 volumio volumio[5879]: info: Oct 19 19:12:14 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:14 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:14 volumio volumio[5879]: info: Oct 19 19:12:14 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:14 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:14 volumio volumio[5879]: info: sendMpdCommand status took 4 milliseconds Oct 19 19:12:14 volumio volumio[5879]: info: sendMpdCommand status took 2 milliseconds Oct 19 19:12:14 volumio volumio[5879]: info: sendMpdCommand status took 1 milliseconds Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:14 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 19 19:12:14 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 19 19:12:14 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:14 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:14 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:14 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:14 volumio volumio[5879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18441,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"877 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:14 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::syncState stateService pause Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::stPlaybackTimer Oct 19 19:12:14 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:14 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:14 volumio volumio[5879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18441,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"877 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:14 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::syncState stateService pause Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::stPlaybackTimer Oct 19 19:12:14 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:14 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:14 volumio volumio[5879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":18441,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"877 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:14 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::syncState stateService pause Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::stPlaybackTimer Oct 19 19:12:14 volumio volumio[5879]: info: ------------------------------ 51ms Oct 19 19:12:14 volumio volumio[5879]: info: ------------------------------ 51ms Oct 19 19:12:14 volumio volumio[5879]: info: ------------------------------ 50ms Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:14 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:14 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:14 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:14 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:14 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:14 volumio volumio[5879]: info: touch_display: Setting screensaver timeout to 15 seconds. Oct 19 19:12:14 volumio volumio[5879]: info: peppy_screensaver: Start PeppyMeter Oct 19 19:12:16 volumio volumio[5879]: info: Oct 19 19:12:16 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:16 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:16 volumio volumio[5879]: info: Oct 19 19:12:16 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:16 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:16 volumio volumio[5879]: info: Oct 19 19:12:16 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:16 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:16 volumio volumio[5879]: info: sendMpdCommand status took 2 milliseconds Oct 19 19:12:16 volumio volumio[5879]: info: sendMpdCommand status took 2 milliseconds Oct 19 19:12:16 volumio volumio[5879]: info: sendMpdCommand status took 1 milliseconds Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:16 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 19:12:16 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 19 19:12:16 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:16 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:16 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:16 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:16 volumio volumio[5879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19173,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"862 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:16 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::syncState stateService play Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:16 volumio volumio[5879]: info: Received an update from plugin. extracting info from payload Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:16 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:16 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:16 volumio volumio[5879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19173,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"859 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:16 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::syncState stateService play Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:16 volumio volumio[5879]: info: Received an update from plugin. extracting info from payload Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:16 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:16 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:16 volumio volumio[5879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":19173,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"859 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:16 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::syncState stateService play Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:16 volumio volumio[5879]: info: Received an update from plugin. extracting info from payload Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:16 volumio volumio[5879]: info: ------------------------------ 64ms Oct 19 19:12:16 volumio volumio[5879]: info: ------------------------------ 62ms Oct 19 19:12:16 volumio volumio[5879]: info: ------------------------------ 63ms Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:16 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:16 volumio volumio[5879]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 19 19:12:17 volumio volumio[5879]: info: Oct 19 19:12:17 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:17 volumio volumio[5879]: info: Oct 19 19:12:17 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:17 volumio volumio[5879]: info: Oct 19 19:12:17 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand status took 3 milliseconds Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand status took 2 milliseconds Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand status took 1 milliseconds Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:17 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:17 volumio volumio[5879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20477,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:17 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState stateService pause Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::stPlaybackTimer Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:17 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:17 volumio volumio[5879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20477,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:17 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState stateService pause Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::stPlaybackTimer Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:17 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:17 volumio volumio[5879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20477,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"885 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:17 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState stateService pause Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::stPlaybackTimer Oct 19 19:12:17 volumio volumio[5879]: info: ------------------------------ 38ms Oct 19 19:12:17 volumio volumio[5879]: info: ------------------------------ 37ms Oct 19 19:12:17 volumio volumio[5879]: info: ------------------------------ 36ms Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:17 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:17 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:17 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:17 volumio volumio[5879]: info: touch_display: Setting screensaver timeout to 15 seconds. Oct 19 19:12:17 volumio volumio[5879]: info: Oct 19 19:12:17 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:17 volumio volumio[5879]: info: Oct 19 19:12:17 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:17 volumio volumio[5879]: info: Oct 19 19:12:17 volumio volumio[5879]: ---------------------------- MPD announces state update: player Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::getState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand status Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand status took 3 milliseconds Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand status took 2 milliseconds Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand status took 1 milliseconds Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseState Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 19 19:12:17 volumio volumio[5879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:17 volumio volumio[5879]: verbose: ControllerMpd::parseTrackInfo Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:17 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:17 volumio volumio[5879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21209,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"869 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:17 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState stateService play Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:17 volumio volumio[5879]: info: Received an update from plugin. extracting info from payload Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:17 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:17 volumio volumio[5879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21209,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"869 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:17 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState stateService play Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:17 volumio volumio[5879]: info: Received an update from plugin. extracting info from payload Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: ControllerMpd::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::servicePushState Oct 19 19:12:17 volumio volumio[5879]: verbose: In UPNP mode Oct 19 19:12:17 volumio volumio[5879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":21209,"duration":234,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"869 Kbps","isStreaming":false,"title":"Как у Кобейна","artist":"Йорш","album":"Счастье! Конец","uri":"http://192.168.1.102:43363/c2d137be539bcc996c3d95fe72e80fc2/primary%3ADownload%2FTopping%2FDeezer%2FDeezer%2F%D0%99%D0%BE%D1%80%D1%88%20-%20%D0%9A%D0%B0%D0%BA%20%D1%83%20%D0%9A%D0%BE%D0%B1%D0%B5%D0%B9%D0%BD%D0%B0.flac","trackType":"flac"} Oct 19 19:12:17 volumio volumio[5879]: verbose: CURRENT POSITION 0 Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState stateService play Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::syncState currentStatus play Oct 19 19:12:17 volumio volumio[5879]: info: Received an update from plugin. extracting info from payload Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreStateMachine::pushState Oct 19 19:12:17 volumio volumio[5879]: info: CoreCommandRouter::volumioPushState Oct 19 19:12:17 volumio volumio[5879]: info: ------------------------------ 71ms Oct 19 19:12:17 volumio volumio[5879]: info: ------------------------------ 70ms Oct 19 19:12:17 volumio volumio[5879]: info: ------------------------------ 70ms Oct 19 19:12:18 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreCommandRouter::volumioGetQueue Oct 19 19:12:18 volumio volumio[5879]: info: CoreStateMachine::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: CorePlayQueue::getQueue Oct 19 19:12:18 volumio volumio[5879]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 19 19:12:30 volumio volumio[5879]: info: Tunnel connection is inactive, restarting it Oct 19 19:12:30 volumio volumio[5879]: info: Starting Tunnel 1 Oct 19 19:12:30 volumio volumio[5879]: info: Starting Tunnel Connection Checker Oct 19 19:12:30 volumio sudo[8535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 19 19:12:30 volumio sudo[8535]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 19:12:30 volumio autossh[7180]: received signal to exit (15) Oct 19 19:12:30 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 19 19:12:30 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 19 19:12:30 volumio systemd[1]: sshtunnel.service: Succeeded. Oct 19 19:12:30 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 19 19:12:30 volumio volumio[5879]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 19 19:12:30 volumio volumio[5879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 19 19:12:30 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 19 19:12:30 volumio sudo[8535]: pam_unix(sudo:session): session closed for user root Oct 19 19:12:30 volumio volumio[5879]: info: Remote SSH Started Oct 19 19:12:30 volumio autossh[8539]: port set to 0, monitoring disabled Oct 19 19:12:30 volumio autossh[8539]: starting ssh (count 1) Oct 19 19:12:30 volumio autossh[8539]: ssh child pid is 8542 Oct 19 19:12:30 volumio volumio[5879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 19 19:12:30 volumio volumio[5879]: info: CoreCommandRouter::volumioGetState Oct 19 19:12:34 volumio volumio[5879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 19 19:12:34 volumio volumio[5879]: info: CoreCommandRouter::volumioGetState Oct 19 19:12:34 volumio volumio[5879]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%D0%99%D0%BE%D1%80%D1%88/%D0%A1%D1%87%D0%B0%D1%81%D1%82%D1%8C%D0%B5!%20%D0%9A%D0%BE%D0%BD%D0%B5%D1%86/2f6f1618-9603-4fcf-a4c8-08ca8f34e647.jpg' Oct 19 19:12:59 volumio volumiossh-tunnel[8537]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Oct 19 19:13:26 volumio volumio[5879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 19:13:26 volumio volumio[5879]: Error: connect ETIMEDOUT 146.75.118.79:443 Oct 19 19:13:26 volumio volumio[5879]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 19 19:13:26 volumio volumio[5879]: errno: -110, Oct 19 19:13:26 volumio volumio[5879]: code: 'ETIMEDOUT', Oct 19 19:13:26 volumio volumio[5879]: syscall: 'connect', Oct 19 19:13:26 volumio volumio[5879]: address: '146.75.118.79', Oct 19 19:13:26 volumio volumio[5879]: port: 443 Oct 19 19:13:26 volumio volumio[5879]: } Oct 19 19:13:26 volumio volumio[5879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 19:13:26 volumio sudo[8913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 19:12 Oct 19 19:13:26 volumio sudo[8913]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"