-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Sun 2024-07-14 17:57:08 PDT. -- Jul 14 17:56:02 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 14 17:56:02 volumio volumio[893]: info: CURURI: music-library Jul 14 17:56:02 volumio volumio[893]: info: Preload queue cleared Jul 14 17:56:05 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 14 17:56:05 volumio volumio[893]: info: CURURI: music-library/NAS Jul 14 17:56:06 volumio volumio[893]: info: Preload queue cleared Jul 14 17:56:07 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 14 17:56:07 volumio volumio[893]: info: CURURI: music-library/NAS/New Jul 14 17:56:07 volumio volumio[893]: info: Preload queue cleared Jul 14 17:56:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 14 17:56:13 volumio volumio[893]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 14 17:56:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 14 17:56:13 volumio volumio[893]: info: Received Get System Version Jul 14 17:56:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 14 17:56:13 volumio volumio[893]: info: Received Get System Info Jul 14 17:56:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 14 17:56:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 14 17:56:13 volumio volumio[893]: info: Discovery: Getting this device information Jul 14 17:56:13 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jul 14 17:56:13 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jul 14 17:56:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 14 17:56:13 volumio volumio[893]: Searching plugin music_service/mpd Jul 14 17:56:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 14 17:56:15 volumio volumio[893]: info: All search sources collected, pushing search results Jul 14 17:56:15 volumio volumio[893]: Searching plugin music_service/mpd Jul 14 17:56:15 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 14 17:56:19 volumio volumio[893]: info: All search sources collected, pushing search results Jul 14 17:56:19 volumio volumio[893]: Searching plugin music_service/mpd Jul 14 17:56:19 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 14 17:56:19 volumio volumio[893]: Searching plugin music_service/mpd Jul 14 17:56:19 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 14 17:56:20 volumio volumio[893]: info: All search sources collected, pushing search results Jul 14 17:56:22 volumio volumio[893]: info: All search sources collected, pushing search results Jul 14 17:56:55 volumio volumio[893]: info: Preload queue cleared Jul 14 17:56:55 volumio volumio[893]: info: CoreCommandRouter::volumioAddQueueItems Jul 14 17:56:55 volumio volumio[893]: info: CoreStateMachine::addQueueItems Jul 14 17:56:56 volumio volumio[893]: info: CorePlayQueue::addQueueItems Jul 14 17:56:56 volumio volumio[893]: info: Preload queue cleared Jul 14 17:56:56 volumio volumio[893]: info: Adding Item to queue: music-library/NAS/New/Eva Cassidy/1997. Eva Cassidy - Live At Blues Alley/08. Fields Of Gold.flac Jul 14 17:56:56 volumio volumio[893]: info: Exploding uri music-library/NAS/New/Eva Cassidy/1997. Eva Cassidy - Live At Blues Alley/08. Fields Of Gold.flac in service mpd Jul 14 17:56:56 volumio volumio[893]: info: ALBUMART /albumart?cacheid=568&web=Eva%20Cassidy/Live%20At%20Blues%20Alley/extralarge&path=%2Fmnt%2FNAS%2FNew%2FEva%20Cassidy%2F1997.%20Eva%20Cassidy%20-%20Live%20At%20Blues%20Alley%2F08.%20Fields%20Of%20Gold.flac&metadata=false Jul 14 17:56:56 volumio volumio[893]: info: URI /mnt/NAS/New/Eva Cassidy/1997. Eva Cassidy - Live At Blues Alley/08. Fields Of Gold.flac Jul 14 17:56:56 volumio volumio[893]: info: CoreCommandRouter::volumioPushQueue Jul 14 17:56:56 volumio volumio[893]: info: CorePlayQueue::saveQueue Jul 14 17:56:56 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Jul 14 17:56:56 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Jul 14 17:56:56 volumio volumio[893]: info: CoreCommandRouter::volumioPlay Jul 14 17:56:56 volumio volumio[893]: info: CoreStateMachine::play index 47 Jul 14 17:56:56 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 17:56:56 volumio volumio[893]: info: CoreStateMachine::stop Jul 14 17:56:56 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 17:56:56 volumio volumio[893]: info: CoreStateMachine::play index undefined Jul 14 17:56:56 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 17:56:56 volumio volumio[893]: info: CorePlayQueue::getTrack 47 Jul 14 17:56:56 volumio volumio[893]: info: CoreStateMachine::startPlaybackTimer Jul 14 17:56:56 volumio volumio[893]: info: CorePlayQueue::getTrack 47 Jul 14 17:56:56 volumio volumio[893]: verbose: ControllerMpd::clearAddPlayTracks NAS/New/Eva Cassidy/1997. Eva Cassidy - Live At Blues Alley/08. Fields Of Gold.flac Jul 14 17:56:56 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 17:56:56 volumio volumio[893]: info: sendMpdCommand stop took 37 milliseconds Jul 14 17:56:56 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Jul 14 17:56:56 volumio volumio[893]: info: Jul 14 17:56:56 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jul 14 17:56:56 volumio volumio[893]: info: Ignoring MPD Status Update Jul 14 17:56:56 volumio volumio[893]: info: sendMpdCommand clear took 15 milliseconds Jul 14 17:56:56 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand add "NAS/New/Eva Cassidy/1997. Eva Cassidy - Live At Blues Alley/08. Fields Of Gold.flac" Jul 14 17:56:56 volumio volumio[893]: error: updateQueue error: null Jul 14 17:56:56 volumio volumio[893]: info: Jul 14 17:56:56 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jul 14 17:56:56 volumio volumio[893]: info: Ignoring MPD Status Update Jul 14 17:56:56 volumio volumio[893]: info: ------------------------------ 8ms Jul 14 17:56:56 volumio volumio[893]: info: sendMpdCommand add "NAS/New/Eva Cassidy/1997. Eva Cassidy - Live At Blues Alley/08. Fields Of Gold.flac" took 6 milliseconds Jul 14 17:56:56 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand play Jul 14 17:56:56 volumio volumio[893]: error: Upnp client error: Error: This socket has been ended by the other party Jul 14 17:56:56 volumio volumio[893]: info: ------------------------------ 12ms Jul 14 17:56:56 volumio volumio[893]: info: sendMpdCommand play took 10 milliseconds Jul 14 17:56:56 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Jul 14 17:56:59 volumio volumio[893]: info: Jul 14 17:56:59 volumio volumio[893]: ---------------------------- MPD announces state update: player Jul 14 17:56:59 volumio volumio[893]: info: ControllerMpd::getState Jul 14 17:56:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jul 14 17:56:59 volumio volumio[893]: info: Jul 14 17:56:59 volumio volumio[893]: ---------------------------- MPD announces state update: player Jul 14 17:56:59 volumio volumio[893]: info: ControllerMpd::getState Jul 14 17:56:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jul 14 17:56:59 volumio volumio[893]: info: sendMpdCommand status took 6 milliseconds Jul 14 17:56:59 volumio volumio[893]: verbose: ControllerMpd::parseState Jul 14 17:56:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 17:56:59 volumio volumio[893]: info: sendMpdCommand status took 11 milliseconds Jul 14 17:56:59 volumio volumio[893]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 14 17:56:59 volumio volumio[893]: verbose: ControllerMpd::parseState Jul 14 17:56:59 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 17:56:59 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jul 14 17:56:59 volumio volumio[893]: info: ControllerMpd::pushState Jul 14 17:56:59 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jul 14 17:56:59 volumio volumio[893]: info: CorePlayQueue::getTrack 47 Jul 14 17:56:59 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3474,"duration":299,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"573 Kbps","isStreaming":false,"title":"Fields Of Gold","artist":"Eva Cassidy","album":"Live At Blues Alley","uri":"NAS/New/Eva Cassidy/1997. Eva Cassidy - Live At Blues Alley/08. Fields Of Gold.flac","trackType":"flac"} Jul 14 17:56:59 volumio volumio[893]: verbose: CURRENT POSITION 47 Jul 14 17:56:59 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jul 14 17:56:59 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jul 14 17:56:59 volumio volumio[893]: info: ------------------------------ 25ms Jul 14 17:56:59 volumio volumio[893]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 14 17:56:59 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jul 14 17:56:59 volumio volumio[893]: info: ControllerMpd::pushState Jul 14 17:56:59 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jul 14 17:56:59 volumio volumio[893]: info: CorePlayQueue::getTrack 47 Jul 14 17:56:59 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3474,"duration":299,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"573 Kbps","isStreaming":false,"title":"Fields Of Gold","artist":"Eva Cassidy","album":"Live At Blues Alley","uri":"NAS/New/Eva Cassidy/1997. Eva Cassidy - Live At Blues Alley/08. Fields Of Gold.flac","trackType":"flac"} Jul 14 17:56:59 volumio volumio[893]: verbose: CURRENT POSITION 47 Jul 14 17:56:59 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jul 14 17:56:59 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jul 14 17:56:59 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jul 14 17:56:59 volumio volumio[893]: info: CoreStateMachine::pushState Jul 14 17:56:59 volumio volumio[893]: info: CorePlayQueue::getTrack 47 Jul 14 17:56:59 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 17:56:59 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 14 17:56:59 volumio volumio[893]: info: CoreStateMachine::pushState Jul 14 17:56:59 volumio volumio[893]: info: CorePlayQueue::getTrack 47 Jul 14 17:56:59 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jul 14 17:56:59 volumio volumio[893]: info: ------------------------------ 39ms Jul 14 17:57:07 volumio volumio[893]: Upnp client error: Error: This socket has been ended by the other party Jul 14 17:57:07 volumio volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 17:57:07 volumio volumio[893]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Jul 14 17:57:07 volumio volumio[893]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jul 14 17:57:07 volumio volumio[893]: errno: -3007, Jul 14 17:57:07 volumio volumio[893]: code: 'ENOTFOUND', Jul 14 17:57:07 volumio volumio[893]: syscall: 'getaddrinfo', Jul 14 17:57:07 volumio volumio[893]: hostname: 'lastfm.freetls.fastly.net' Jul 14 17:57:07 volumio volumio[893]: } Jul 14 17:57:07 volumio volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 17:57:08 volumio sudo[23885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-14 17:56 Jul 14 17:57:08 volumio sudo[23885]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"