-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2024-08-31 20:25:59 CEST. -- Aug 31 20:25:28 volumio volumio[961]: info: CorePlayQueue::getTrack 8 Aug 31 20:25:28 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:28 volumio volumio[961]: info: Prefetching next song Aug 31 20:25:28 volumio volumio[961]: info: DOING PREFETCH IN MPD Aug 31 20:25:28 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand add "USB/USB-NØGLE/Musik/Colin James/Sudden Stop/07 Cross My Heart.mp3" Aug 31 20:25:28 volumio volumio[961]: info: Aug 31 20:25:28 volumio volumio[961]: ---------------------------- MPD announces system playlist update Aug 31 20:25:28 volumio volumio[961]: info: Ignoring MPD Status Update Aug 31 20:25:28 volumio volumio[961]: info: sendMpdCommand add "USB/USB-NØGLE/Musik/Colin James/Sudden Stop/07 Cross My Heart.mp3" took 2 milliseconds Aug 31 20:25:28 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 31 20:25:28 volumio volumio[961]: info: Aug 31 20:25:28 volumio volumio[961]: ---------------------------- MPD announces system playlist update Aug 31 20:25:28 volumio volumio[961]: info: Ignoring MPD Status Update Aug 31 20:25:28 volumio volumio[961]: info: Aug 31 20:25:28 volumio volumio[961]: ---------------------------- MPD announces system playlist update Aug 31 20:25:28 volumio volumio[961]: info: Ignoring MPD Status Update Aug 31 20:25:28 volumio volumio[961]: info: ------------------------------ 3ms Aug 31 20:25:28 volumio volumio[961]: info: sendMpdCommand consume 1 took 2 milliseconds Aug 31 20:25:28 volumio volumio[961]: info: ------------------------------ 2ms Aug 31 20:25:28 volumio volumio[961]: info: ------------------------------ 2ms Aug 31 20:25:33 volumio volumio[961]: info: CoreStateMachine::startPlaybackTimer Aug 31 20:25:33 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:33 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:33 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:33 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:25:33 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:34 volumio volumio[961]: info: Aug 31 20:25:34 volumio volumio[961]: ---------------------------- MPD announces system playlist update Aug 31 20:25:34 volumio volumio[961]: info: Ignoring MPD Status Update Aug 31 20:25:34 volumio volumio[961]: info: Aug 31 20:25:34 volumio volumio[961]: ---------------------------- MPD announces state update: player Aug 31 20:25:34 volumio volumio[961]: info: ControllerMpd::getState Aug 31 20:25:34 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:25:34 volumio volumio[961]: info: Aug 31 20:25:34 volumio volumio[961]: ---------------------------- MPD announces system playlist update Aug 31 20:25:34 volumio volumio[961]: info: Ignoring MPD Status Update Aug 31 20:25:34 volumio volumio[961]: info: Aug 31 20:25:34 volumio volumio[961]: ---------------------------- MPD announces state update: player Aug 31 20:25:34 volumio volumio[961]: info: ControllerMpd::getState Aug 31 20:25:34 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:25:34 volumio volumio[961]: info: Aug 31 20:25:34 volumio volumio[961]: ---------------------------- MPD announces system playlist update Aug 31 20:25:34 volumio volumio[961]: info: Ignoring MPD Status Update Aug 31 20:25:34 volumio volumio[961]: info: Aug 31 20:25:34 volumio volumio[961]: ---------------------------- MPD announces state update: player Aug 31 20:25:34 volumio volumio[961]: info: ControllerMpd::getState Aug 31 20:25:34 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:25:35 volumio volumio[961]: info: Aug 31 20:25:35 volumio volumio[961]: ---------------------------- MPD announces state update: player Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 903ms Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand status took 902 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::getState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 904ms Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand status took 904 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 903ms Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand status took 903 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand status took 2 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::pushState Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::servicePushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cross My Heart","artist":"Colin James","album":"Sudden Stop","uri":"USB/USB-NØGLE/Musik/Colin James/Sudden Stop/07 Cross My Heart.mp3","trackType":"mp3"} Aug 31 20:25:35 volumio volumio[961]: verbose: CURRENT POSITION 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:25:35 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 919ms Aug 31 20:25:35 volumio volumio[961]: info: Aug 31 20:25:35 volumio volumio[961]: ---------------------------- MPD announces state update: player Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::getState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:25:35 volumio volumio[961]: info: Aug 31 20:25:35 volumio volumio[961]: ---------------------------- MPD announces state update: player Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::getState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand status took 3 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand status took 2 milliseconds Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseState Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::pushState Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::servicePushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cross My Heart","artist":"Colin James","album":"Sudden Stop","uri":"USB/USB-NØGLE/Musik/Colin James/Sudden Stop/07 Cross My Heart.mp3","trackType":"mp3"} Aug 31 20:25:35 volumio volumio[961]: verbose: CURRENT POSITION 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:25:35 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::pushState Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::servicePushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cross My Heart","artist":"Colin James","album":"Sudden Stop","uri":"USB/USB-NØGLE/Musik/Colin James/Sudden Stop/07 Cross My Heart.mp3","trackType":"mp3"} Aug 31 20:25:35 volumio volumio[961]: verbose: CURRENT POSITION 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:25:35 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::pushState Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::servicePushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cross My Heart","artist":"Colin James","album":"Sudden Stop","uri":"USB/USB-NØGLE/Musik/Colin James/Sudden Stop/07 Cross My Heart.mp3","trackType":"mp3"} Aug 31 20:25:35 volumio volumio[961]: verbose: CURRENT POSITION 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:25:35 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 951ms Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 951ms Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 53ms Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand playlistinfo took 41 milliseconds Aug 31 20:25:35 volumio volumio[961]: info: sendMpdCommand playlistinfo took 34 milliseconds Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:25:35 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::pushState Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::servicePushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cross My Heart","artist":"Colin James","album":"Sudden Stop","uri":"USB/USB-NØGLE/Musik/Colin James/Sudden Stop/07 Cross My Heart.mp3","trackType":"mp3"} Aug 31 20:25:35 volumio volumio[961]: verbose: CURRENT POSITION 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:25:35 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: ControllerMpd::pushState Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::servicePushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cross My Heart","artist":"Colin James","album":"Sudden Stop","uri":"USB/USB-NØGLE/Musik/Colin James/Sudden Stop/07 Cross My Heart.mp3","trackType":"mp3"} Aug 31 20:25:35 volumio volumio[961]: verbose: CURRENT POSITION 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:25:35 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: CoreStateMachine::pushState Aug 31 20:25:35 volumio volumio[961]: info: CorePlayQueue::getTrack 23 Aug 31 20:25:35 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 68ms Aug 31 20:25:35 volumio volumio[961]: info: ------------------------------ 68ms Aug 31 20:25:56 volumio volumio[961]: verbose: New Socket.io Connection to 192.168.40.247 from 192.168.40.16 UA: Mozilla/5.0 (X11; Linux x86_64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 31 20:25:59 volumio volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 20:25:59 volumio volumio[961]: Error: read ECONNRESET Aug 31 20:25:59 volumio volumio[961]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Aug 31 20:25:59 volumio volumio[961]: errno: -104, Aug 31 20:25:59 volumio volumio[961]: code: 'ECONNRESET', Aug 31 20:25:59 volumio volumio[961]: syscall: 'read' Aug 31 20:25:59 volumio volumio[961]: } Aug 31 20:25:59 volumio volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 20:25:59 volumio sudo[12582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 20:24 Aug 31 20:25:59 volumio sudo[12582]: 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"