-- Logs begin at Thu 2019-02-14 17:12:00 +07, end at Thu 2025-02-27 19:06:33 +07. -- Feb 27 19:05:43 volumio volumio[799]: info: CorePlayQueue::getTrack 337 Feb 27 19:05:43 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:43 volumio volumio[799]: info: Prefetching next song Feb 27 19:05:43 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee3ba745bfa247b25c93907c9f0e955e03d7a54888f567426aa521a9c4b076ac%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67ce22a2%2F42727%2F1750370%2Fb8240f44.47622300.11.31987927%2F320.mp3&ext=.mp3" Feb 27 19:05:43 volumio volumio[799]: info: Feb 27 19:05:43 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:43 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:43 volumio volumio[799]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee3ba745bfa247b25c93907c9f0e955e03d7a54888f567426aa521a9c4b076ac%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67ce22a2%2F42727%2F1750370%2Fb8240f44.47622300.11.31987927%2F320.mp3&ext=.mp3" took 2 milliseconds Feb 27 19:05:43 volumio volumio[799]: verbose: MPD COMMAND [object Object] Feb 27 19:05:43 volumio volumio[799]: verbose: MPD COMMAND [object Object] Feb 27 19:05:43 volumio volumio[799]: verbose: MPD COMMAND [object Object] Feb 27 19:05:43 volumio volumio[799]: info: Feb 27 19:05:43 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:43 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:43 volumio volumio[799]: info: Feb 27 19:05:43 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:43 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:43 volumio volumio[799]: info: Feb 27 19:05:43 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:43 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:43 volumio volumio[799]: info: ------------------------------ 14ms Feb 27 19:05:43 volumio volumio[799]: info: ------------------------------ 9ms Feb 27 19:05:43 volumio volumio[799]: info: ------------------------------ 2ms Feb 27 19:05:43 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 27 19:05:43 volumio volumio[799]: info: Feb 27 19:05:43 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:43 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:43 volumio volumio[799]: info: Feb 27 19:05:43 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:43 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:43 volumio volumio[799]: info: ------------------------------ 4ms Feb 27 19:05:43 volumio volumio[799]: info: sendMpdCommand consume 1 took 3 milliseconds Feb 27 19:05:43 volumio volumio[799]: info: ------------------------------ 2ms Feb 27 19:05:43 volumio volumio[799]: info: ------------------------------ 2ms Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::startPlaybackTimer Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: info: Feb 27 19:05:47 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:47 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:47 volumio volumio[799]: info: Feb 27 19:05:47 volumio volumio[799]: ---------------------------- MPD announces state update: player Feb 27 19:05:47 volumio volumio[799]: info: ControllerMpd::getState Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status Feb 27 19:05:47 volumio volumio[799]: info: Feb 27 19:05:47 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:47 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:47 volumio volumio[799]: info: Feb 27 19:05:47 volumio volumio[799]: ---------------------------- MPD announces state update: player Feb 27 19:05:47 volumio volumio[799]: info: ControllerMpd::getState Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status Feb 27 19:05:47 volumio volumio[799]: info: Feb 27 19:05:47 volumio volumio[799]: ---------------------------- MPD announces system playlist update Feb 27 19:05:47 volumio volumio[799]: info: Ignoring MPD Status Update Feb 27 19:05:47 volumio volumio[799]: info: Feb 27 19:05:47 volumio volumio[799]: ---------------------------- MPD announces state update: player Feb 27 19:05:47 volumio volumio[799]: info: ControllerMpd::getState Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand status Feb 27 19:05:47 volumio volumio[799]: info: ------------------------------ 4ms Feb 27 19:05:47 volumio volumio[799]: info: sendMpdCommand status took 4 milliseconds Feb 27 19:05:47 volumio volumio[799]: info: ------------------------------ 4ms Feb 27 19:05:47 volumio volumio[799]: info: sendMpdCommand status took 3 milliseconds Feb 27 19:05:47 volumio volumio[799]: info: ------------------------------ 3ms Feb 27 19:05:47 volumio volumio[799]: info: sendMpdCommand status took 2 milliseconds Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::parseState Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::parseState Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::parseState Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 27 19:05:47 volumio volumio[799]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo Feb 27 19:05:47 volumio volumio[799]: info: ControllerMpd::pushState Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::servicePushState Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":274,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Time Moves Slow","artist":"BADBADNOTGOOD","album":"IV","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee3ba745bfa247b25c93907c9f0e955e03d7a54888f567426aa521a9c4b076ac%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67ce22a2%2F42727%2F1750370%2Fb8240f44.47622300.11.31987927%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 27 19:05:47 volumio volumio[799]: verbose: CURRENT POSITION 770 Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::syncState stateService play Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play Feb 27 19:05:47 volumio volumio[799]: info: Received an update from plugin. extracting info from payload Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::pushState Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::pushState Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Feb 27 19:05:47 volumio volumio[799]: info: ------------------------------ 25ms Feb 27 19:05:47 volumio volumio[799]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 27 19:05:47 volumio volumio[799]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo Feb 27 19:05:47 volumio volumio[799]: verbose: ControllerMpd::parseTrackInfo Feb 27 19:05:47 volumio volumio[799]: info: ControllerMpd::pushState Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::servicePushState Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":274,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Time Moves Slow","artist":"BADBADNOTGOOD","album":"IV","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee3ba745bfa247b25c93907c9f0e955e03d7a54888f567426aa521a9c4b076ac%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67ce22a2%2F42727%2F1750370%2Fb8240f44.47622300.11.31987927%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 27 19:05:47 volumio volumio[799]: verbose: CURRENT POSITION 770 Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::syncState stateService play Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play Feb 27 19:05:47 volumio volumio[799]: info: Received an update from plugin. extracting info from payload Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::pushState Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::pushState Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Feb 27 19:05:47 volumio volumio[799]: info: ControllerMpd::pushState Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::servicePushState Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":274,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Time Moves Slow","artist":"BADBADNOTGOOD","album":"IV","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dee3ba745bfa247b25c93907c9f0e955e03d7a54888f567426aa521a9c4b076ac%2Clid%3D1521%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67ce22a2%2F42727%2F1750370%2Fb8240f44.47622300.11.31987927%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 27 19:05:47 volumio volumio[799]: verbose: CURRENT POSITION 770 Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::syncState stateService play Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::syncState currentStatus play Feb 27 19:05:47 volumio volumio[799]: info: Received an update from plugin. extracting info from payload Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::pushState Feb 27 19:05:47 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:47 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Feb 27 19:05:47 volumio volumio[799]: info: CoreStateMachine::pushState Feb 27 19:05:48 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:48 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Feb 27 19:05:48 volumio volumio[799]: info: ------------------------------ 122ms Feb 27 19:05:48 volumio volumio[799]: info: ------------------------------ 120ms Feb 27 19:05:48 volumio volumio[799]: info: CoreStateMachine::pushState Feb 27 19:05:48 volumio volumio[799]: info: CorePlayQueue::getTrack 770 Feb 27 19:05:48 volumio volumio[799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 19:05:48 volumio volumio[799]: info: CoreCommandRouter::volumioPushState Feb 27 19:06:32 volumio volumio[799]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 19:06:32 volumio volumio[799]: Error: read ECONNRESET Feb 27 19:06:32 volumio volumio[799]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Feb 27 19:06:32 volumio volumio[799]: errno: -104, Feb 27 19:06:32 volumio volumio[799]: code: 'ECONNRESET', Feb 27 19:06:32 volumio volumio[799]: syscall: 'read' Feb 27 19:06:32 volumio volumio[799]: } Feb 27 19:06:32 volumio volumio[799]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 19:06:33 volumio sudo[18057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-27 19:05 Feb 27 19:06:33 volumio sudo[18057]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"