-- Logs begin at Sun 2024-09-01 08:02:52 EDT, end at Mon 2024-09-02 14:46:22 EDT. -- Sep 02 14:46:11 speaker3ox volumio[27335]: info: Sep 02 14:46:11 speaker3ox volumio[27335]: ---------------------------- MPD announces system playlist update Sep 02 14:46:11 speaker3ox volumio[27335]: info: Ignoring MPD Status Update Sep 02 14:46:11 speaker3ox volumio[27335]: info: Sep 02 14:46:11 speaker3ox volumio[27335]: ---------------------------- MPD announces state update: player Sep 02 14:46:11 speaker3ox volumio[27335]: info: ControllerMpd::getState Sep 02 14:46:11 speaker3ox volumio[27335]: verbose: ControllerMpd::sendMpdCommand status Sep 02 14:46:11 speaker3ox volumio[27335]: info: Sep 02 14:46:11 speaker3ox volumio[27335]: ---------------------------- MPD announces system playlist update Sep 02 14:46:11 speaker3ox volumio[27335]: info: Ignoring MPD Status Update Sep 02 14:46:11 speaker3ox volumio[27335]: info: Sep 02 14:46:11 speaker3ox volumio[27335]: ---------------------------- MPD announces state update: player Sep 02 14:46:11 speaker3ox volumio[27335]: info: ControllerMpd::getState Sep 02 14:46:11 speaker3ox volumio[27335]: verbose: ControllerMpd::sendMpdCommand status Sep 02 14:46:11 speaker3ox volumio[27335]: info: Sep 02 14:46:11 speaker3ox volumio[27335]: ---------------------------- MPD announces system playlist update Sep 02 14:46:11 speaker3ox volumio[27335]: info: Ignoring MPD Status Update Sep 02 14:46:11 speaker3ox volumio[27335]: info: Sep 02 14:46:11 speaker3ox volumio[27335]: ---------------------------- MPD announces state update: player Sep 02 14:46:11 speaker3ox volumio[27335]: info: ControllerMpd::getState Sep 02 14:46:11 speaker3ox volumio[27335]: verbose: ControllerMpd::sendMpdCommand status Sep 02 14:46:11 speaker3ox volumio[27335]: info: ------------------------------ 9ms Sep 02 14:46:11 speaker3ox volumio[27335]: info: sendMpdCommand status took 8 milliseconds Sep 02 14:46:11 speaker3ox volumio[27335]: info: ------------------------------ 7ms Sep 02 14:46:11 speaker3ox volumio[27335]: info: sendMpdCommand status took 5 milliseconds Sep 02 14:46:11 speaker3ox volumio[27335]: info: ------------------------------ 5ms Sep 02 14:46:11 speaker3ox volumio[27335]: info: sendMpdCommand status took 4 milliseconds Sep 02 14:46:11 speaker3ox volumio[27335]: verbose: ControllerMpd::parseState Sep 02 14:46:11 speaker3ox volumio[27335]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 14:46:11 speaker3ox volumio[27335]: verbose: ControllerMpd::parseState Sep 02 14:46:11 speaker3ox volumio[27335]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: ControllerMpd::parseState Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 14:46:12 speaker3ox volumio[27335]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 02 14:46:12 speaker3ox volumio[27335]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 02 14:46:12 speaker3ox volumio[27335]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: ControllerMpd::parseTrackInfo Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: ControllerMpd::parseTrackInfo Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: ControllerMpd::parseTrackInfo Sep 02 14:46:12 speaker3ox volumio[27335]: info: ControllerMpd::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::servicePushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CorePlayQueue::getTrack 0 Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1886928,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Toto - Stop Loving You","artist":"80s80s Rock","album":null,"uri":"https://streams.80s80s.de/rock/mp3-192/tunein/","trackType":""} Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: CURRENT POSITION 0 Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::syncState stateService play Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::syncState currentStatus play Sep 02 14:46:12 speaker3ox volumio[27335]: info: Received an update from plugin. extracting info from payload Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::volumioPushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::volumioPushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: ControllerMpd::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::servicePushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CorePlayQueue::getTrack 0 Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1886928,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Toto - Stop Loving You","artist":"80s80s Rock","album":null,"uri":"https://streams.80s80s.de/rock/mp3-192/tunein/","trackType":""} Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: CURRENT POSITION 0 Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::syncState stateService play Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::syncState currentStatus play Sep 02 14:46:12 speaker3ox volumio[27335]: info: Received an update from plugin. extracting info from payload Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::volumioPushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::volumioPushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: ControllerMpd::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::servicePushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CorePlayQueue::getTrack 0 Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1886928,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Toto - Stop Loving You","artist":"80s80s Rock","album":null,"uri":"https://streams.80s80s.de/rock/mp3-192/tunein/","trackType":""} Sep 02 14:46:12 speaker3ox volumio[27335]: verbose: CURRENT POSITION 0 Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::syncState stateService play Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::syncState currentStatus play Sep 02 14:46:12 speaker3ox volumio[27335]: info: Received an update from plugin. extracting info from payload Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::volumioPushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreStateMachine::pushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: CoreCommandRouter::volumioPushState Sep 02 14:46:12 speaker3ox volumio[27335]: info: ------------------------------ 70ms Sep 02 14:46:12 speaker3ox volumio[27335]: info: ------------------------------ 68ms Sep 02 14:46:12 speaker3ox volumio[27335]: info: ------------------------------ 67ms Sep 02 14:46:12 speaker3ox volumio[27335]: info: [LastFM] Split composite title into artist: Toto and title: Stop Loving You Sep 02 14:46:12 speaker3ox volumio[27335]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Stop Loving You","mbid":"cb5dfc4d-ec86-48d0-83a5-3f4c68e82f4e","url":"https://www.last.fm/music/Toto/_/Stop+Loving+You","duration":"269000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"152499","playcount":"757994","artist":{"name":"Toto","mbid":"aab5c954-cabe-432e-899e-1c4f99757327","url":"https://www.last.fm/music/Toto"},"album":{"@":{"position":"10"},"artist":"Toto","title":"Past to Present 1977-1990","mbid":"c3c8cddd-06a8-34a9-a58d-e55f8b5ead33","url":"https://www.last.fm/music/Toto/Past+to+Present+1977-1990","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/7455fc12a77aa1db90523fb966ab9a2e.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/7455fc12a77aa1db90523fb966ab9a2e.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/7455fc12a77aa1db90523fb966ab9a2e.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/7455fc12a77aa1db90523fb966ab9a2e.png","@":{"size":"extralarge"}}]},"userplaycount":"10","userloved":"0","toptags":{"tag":[{"name":"rock","url":"https://www.last.fm/tag/rock"},{"name":"classic rock","url":"https://www.last.fm/tag/classic+rock"},{"name":"80s","url":"https://www.last.fm/tag/80s"},{"name":"pop","url":"https://www.last.fm/tag/pop"},{"name":"Ballad","url":"https://www.last.fm/tag/Ballad"}]}}} Sep 02 14:46:12 speaker3ox volumio[27335]: info: [LastFM] Updated missing track duration: 269000 Sep 02 14:46:12 speaker3ox volumio[27335]: info: [LastFM] Updated missing track album: Past to Present 1977-1990 Sep 02 14:46:22 speaker3ox volumio[27335]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 14:46:22 speaker3ox volumio[27335]: Error: read ETIMEDOUT Sep 02 14:46:22 speaker3ox volumio[27335]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Sep 02 14:46:22 speaker3ox volumio[27335]: errno: -110, Sep 02 14:46:22 speaker3ox volumio[27335]: code: 'ETIMEDOUT', Sep 02 14:46:22 speaker3ox volumio[27335]: syscall: 'read' Sep 02 14:46:22 speaker3ox volumio[27335]: } Sep 02 14:46:22 speaker3ox volumio[27335]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 14:46:22 speaker3ox sudo[29112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 14:45 Sep 02 14:46:22 speaker3ox sudo[29112]: 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"