-- Logs begin at Thu 2024-05-09 12:09:06 CST, end at Thu 2024-05-09 17:38:08 CST. -- May 09 17:37:03 volumio volumio[1911]: info: CoreCommandRouter::volumioGetState May 09 17:37:03 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:37:05 volumio volumio[1911]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 17:37:05 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 09 17:37:05 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 09 17:37:05 volumio volumio[1911]: info: Received Get System Version May 09 17:37:05 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::volumioGetState May 09 17:37:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 09 17:37:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 09 17:37:13 volumio ntpd[4647]: 193.182.111.141 local addr 192.168.5.11 -> May 09 17:37:22 volumio volumio[1911]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 09 17:37:22 volumio volumio[1911]: info: Retrying Login Due to Network Error May 09 17:37:32 volumio volumio[1911]: info: MyVolumio login type: Token May 09 17:37:39 volumio volumio[1911]: info: CoreCommandRouter::volumioGetState May 09 17:37:39 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:37:40 volumio volumio[1911]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 17:37:40 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 09 17:37:40 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 09 17:37:40 volumio volumio[1911]: info: Received Get System Version May 09 17:37:40 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 17:37:44 volumio nmbd[738]: [2024/05/09 17:37:44.880140, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 09 17:37:44 volumio nmbd[738]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.11 for name WORKGROUP<1d>. May 09 17:37:44 volumio nmbd[738]: This response was from IP 192.168.5.105, reporting an IP address of 192.168.5.105. May 09 17:37:55 volumio volumio[1911]: info: CoreCommandRouter::volumioGetState May 09 17:37:55 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:37:58 volumio volumio[1911]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 17:37:58 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 09 17:37:58 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 17:37:58 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 17:37:58 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 17:37:58 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 09 17:37:58 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 09 17:38:02 volumio volumio[1911]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 09 17:38:02 volumio volumio[1911]: info: Retrying Login Due to Network Error May 09 17:38:02 volumio volumio[1911]: info: CoreCommandRouter::volumioPlay May 09 17:38:02 volumio volumio[1911]: info: CoreStateMachine::play index undefined May 09 17:38:02 volumio volumio[1911]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 17:38:02 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:38:02 volumio volumio[1911]: info: CoreStateMachine::startPlaybackTimer May 09 17:38:02 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:38:02 volumio volumio[1911]: info: ControllerMpd::resume May 09 17:38:02 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand play May 09 17:38:02 volumio volumio[1911]: info: sendMpdCommand play took 1 milliseconds May 09 17:38:04 volumio volumio[1911]: info: CoreCommandRouter::volumioPlay May 09 17:38:04 volumio volumio[1911]: info: CoreStateMachine::play index undefined May 09 17:38:04 volumio volumio[1911]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 17:38:04 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:38:04 volumio volumio[1911]: info: CoreStateMachine::startPlaybackTimer May 09 17:38:04 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:38:04 volumio volumio[1911]: info: ControllerMpd::resume May 09 17:38:04 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand play May 09 17:38:04 volumio volumio[1911]: info: sendMpdCommand play took 1 milliseconds May 09 17:38:07 volumio volumio[1911]: info: CoreCommandRouter::volumioGetState May 09 17:38:07 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::volumioNext May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::next May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::stop May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::updateTrackBlock May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrackBlock May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::stPlaybackTimer May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::pushState May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::volumioPushState May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::serviceStop May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 0 May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::serviceStop May 09 17:38:08 volumio volumio[1911]: info: ControllerMpd::stop May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand stop May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand stop took 3 milliseconds May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::play index undefined May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 1 May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::startPlaybackTimer May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 1 May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::clearAddPlayTracks NAS/dietpi/Norah Jones - Feels Like Home/02 - Norah Jones - What Am I To You.dsf May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand stop May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::updateTrackBlock May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrackBlock May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand stop took 1 milliseconds May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand clear May 09 17:38:08 volumio volumio[1911]: info: May 09 17:38:08 volumio volumio[1911]: ---------------------------- MPD announces system playlist update May 09 17:38:08 volumio volumio[1911]: info: Ignoring MPD Status Update May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand clear took 1 milliseconds May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand add "NAS/dietpi/Norah Jones - Feels Like Home/02 - Norah Jones - What Am I To You.dsf" May 09 17:38:08 volumio volumio[1911]: error: updateQueue error: null May 09 17:38:08 volumio volumio[1911]: info: May 09 17:38:08 volumio volumio[1911]: ---------------------------- MPD announces system playlist update May 09 17:38:08 volumio volumio[1911]: info: Ignoring MPD Status Update May 09 17:38:08 volumio volumio[1911]: info: ------------------------------ 1ms May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand add "NAS/dietpi/Norah Jones - Feels Like Home/02 - Norah Jones - What Am I To You.dsf" took 1 milliseconds May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand play May 09 17:38:08 volumio volumio[1911]: info: ------------------------------ 1ms May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand play took 2 milliseconds May 09 17:38:08 volumio volumio[1911]: info: May 09 17:38:08 volumio volumio[1911]: ---------------------------- MPD announces state update: player May 09 17:38:08 volumio volumio[1911]: info: ControllerMpd::getState May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand status May 09 17:38:08 volumio volumio[1911]: info: May 09 17:38:08 volumio volumio[1911]: ---------------------------- MPD announces state update: player May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand status took 13 milliseconds May 09 17:38:08 volumio volumio[1911]: info: ControllerMpd::getState May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand status May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::parseState May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand status took 5 milliseconds May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand playlistinfo took 6 milliseconds May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::parseState May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::parseTrackInfo May 09 17:38:08 volumio volumio[1911]: info: ControllerMpd::pushState May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::servicePushState May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 1 May 09 17:38:08 volumio volumio[1911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"What Am I To You?","artist":"Norah Jones","album":"Feels Like Home","uri":"NAS/dietpi/Norah Jones - Feels Like Home/02 - Norah Jones - What Am I To You.dsf","trackType":"dsf"} May 09 17:38:08 volumio volumio[1911]: verbose: CURRENT POSITION 1 May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::syncState stateService play May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::syncState currentStatus stop May 09 17:38:08 volumio volumio[1911]: info: ------------------------------ 22ms May 09 17:38:08 volumio volumio[1911]: info: sendMpdCommand playlistinfo took 5 milliseconds May 09 17:38:08 volumio volumio[1911]: verbose: ControllerMpd::parseTrackInfo May 09 17:38:08 volumio volumio[1911]: info: ControllerMpd::pushState May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::servicePushState May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 1 May 09 17:38:08 volumio volumio[1911]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":210,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"What Am I To You?","artist":"Norah Jones","album":"Feels Like Home","uri":"NAS/dietpi/Norah Jones - Feels Like Home/02 - Norah Jones - What Am I To You.dsf","trackType":"dsf"} May 09 17:38:08 volumio volumio[1911]: verbose: CURRENT POSITION 1 May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::syncState stateService play May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::syncState currentStatus play May 09 17:38:08 volumio volumio[1911]: info: Received an update from plugin. extracting info from payload May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::pushState May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 1 May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::volumioPushState May 09 17:38:08 volumio volumio[1911]: info: CoreStateMachine::pushState May 09 17:38:08 volumio volumio[1911]: info: CorePlayQueue::getTrack 1 May 09 17:38:08 volumio volumio[1911]: info: CoreCommandRouter::volumioPushState May 09 17:38:08 volumio volumio[1911]: info: ------------------------------ 22ms May 09 17:38:08 volumio volumio[1911]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 17:38:08 volumio volumio[1911]: Error: connect ETIMEDOUT 108.160.166.137:443 May 09 17:38:08 volumio volumio[1911]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 09 17:38:08 volumio volumio[1911]: errno: -110, May 09 17:38:08 volumio volumio[1911]: code: 'ETIMEDOUT', May 09 17:38:08 volumio volumio[1911]: syscall: 'connect', May 09 17:38:08 volumio volumio[1911]: address: '108.160.166.137', May 09 17:38:08 volumio volumio[1911]: port: 443 May 09 17:38:08 volumio volumio[1911]: } May 09 17:38:08 volumio volumio[1911]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 17:38:08 volumio sudo[4809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 17:37 May 09 17:38:08 volumio sudo[4809]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e5e6090399db628f1586eb4951e3592507f5fd38" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="50b5825b3acc4c58b5033699bcdbbd219d6a0836" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Jan 2024 06:49:33 PM CET" VOLUMIO_VERSION="3.601" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="56835594de153eaaeb15ecd23cd26aea"