-- Logs begin at Fri 2025-03-14 20:35:27 CST, end at Fri 2025-03-14 21:50:17 CST. -- Mar 14 21:49:06 volumio kernel: perf: interrupt took too long (3210 > 3168), lowering kernel.perf_event_max_sample_rate to 62250 Mar 14 21:49:09 volumio volumio[12324]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 14 21:49:09 volumio volumio[12324]: info: Retrying Login Due to Network Error Mar 14 21:49:19 volumio volumio[12324]: info: MyVolumio login type: Token Mar 14 21:49:49 volumio volumio[12324]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 14 21:49:49 volumio volumio[12324]: info: Retrying Login Due to Network Error Mar 14 21:49:59 volumio volumio[12324]: info: MyVolumio login type: Token Mar 14 21:50:13 volumio volumio[12324]: info: MPD Database updated - AlbumList cache refreshed Mar 14 21:50:13 volumio volumio[12324]: info: Mar 14 21:50:13 volumio volumio[12324]: ---------------------------- MPD announces state update: database Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:13 volumio volumio[12324]: info: Mar 14 21:50:13 volumio volumio[12324]: ---------------------------- MPD announces state update: update Mar 14 21:50:13 volumio volumio[12324]: info: ControllerMpd::getState Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:13 volumio volumio[12324]: info: ControllerMpd::getState Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:13 volumio volumio[12324]: info: MPD Database updated - AlbumList cache refreshed Mar 14 21:50:13 volumio volumio[12324]: info: Mar 14 21:50:13 volumio volumio[12324]: ---------------------------- MPD announces state update: database Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:13 volumio volumio[12324]: info: Mar 14 21:50:13 volumio volumio[12324]: ---------------------------- MPD announces state update: update Mar 14 21:50:13 volumio volumio[12324]: info: ControllerMpd::getState Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:13 volumio volumio[12324]: info: ControllerMpd::getState Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:13 volumio volumio[12324]: info: MPD Database updated - AlbumList cache refreshed Mar 14 21:50:13 volumio volumio[12324]: info: Mar 14 21:50:13 volumio volumio[12324]: ---------------------------- MPD announces state update: database Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:13 volumio volumio[12324]: info: Mar 14 21:50:13 volumio volumio[12324]: ---------------------------- MPD announces state update: update Mar 14 21:50:13 volumio volumio[12324]: info: ControllerMpd::getState Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:13 volumio volumio[12324]: info: ControllerMpd::getState Mar 14 21:50:13 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand status Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 212 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 212 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 212 milliseconds Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: info: Command Router : Notfying DB Updatefalse Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::Close All Modals sent Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 457 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 457 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 457 milliseconds Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: info: Command Router : Notfying DB Updatefalse Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::Close All Modals sent Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 629 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 629 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand status took 629 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand playlistinfo took 338 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand playlistinfo took 338 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand playlistinfo took 112 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand playlistinfo took 111 milliseconds Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: info: Command Router : Notfying DB Updatefalse Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::Close All Modals sent Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseState Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseTrackInfo Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseTrackInfo Mar 14 21:50:14 volumio volumio[12324]: info: ControllerMpd::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::servicePushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132716,"duration":309,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"So Far Away","artist":"Dire Straits","album":"Brothers In Arms (Remastered)","uri":"NAS/istoreOS/1985 Brothers In Arms (Remastered)/01 So Far Away.m4a","trackType":"m4a"} Mar 14 21:50:14 volumio volumio[12324]: verbose: CURRENT POSITION 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState stateService play Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState currentStatus play Mar 14 21:50:14 volumio volumio[12324]: info: Received an update from plugin. extracting info from payload Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: ControllerMpd::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::servicePushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132716,"duration":309,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"So Far Away","artist":"Dire Straits","album":"Brothers In Arms (Remastered)","uri":"NAS/istoreOS/1985 Brothers In Arms (Remastered)/01 So Far Away.m4a","trackType":"m4a"} Mar 14 21:50:14 volumio volumio[12324]: verbose: CURRENT POSITION 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState stateService play Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState currentStatus play Mar 14 21:50:14 volumio volumio[12324]: info: Received an update from plugin. extracting info from payload Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: ControllerMpd::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::servicePushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132716,"duration":309,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"So Far Away","artist":"Dire Straits","album":"Brothers In Arms (Remastered)","uri":"NAS/istoreOS/1985 Brothers In Arms (Remastered)/01 So Far Away.m4a","trackType":"m4a"} Mar 14 21:50:14 volumio volumio[12324]: verbose: CURRENT POSITION 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState stateService play Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState currentStatus play Mar 14 21:50:14 volumio volumio[12324]: info: Received an update from plugin. extracting info from payload Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: ControllerMpd::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::servicePushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132716,"duration":309,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"So Far Away","artist":"Dire Straits","album":"Brothers In Arms (Remastered)","uri":"NAS/istoreOS/1985 Brothers In Arms (Remastered)/01 So Far Away.m4a","trackType":"m4a"} Mar 14 21:50:14 volumio volumio[12324]: verbose: CURRENT POSITION 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState stateService play Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState currentStatus play Mar 14 21:50:14 volumio volumio[12324]: info: Received an update from plugin. extracting info from payload Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: ------------------------------ 786ms Mar 14 21:50:14 volumio volumio[12324]: info: ------------------------------ 786ms Mar 14 21:50:14 volumio volumio[12324]: info: ------------------------------ 783ms Mar 14 21:50:14 volumio volumio[12324]: info: ------------------------------ 783ms Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand playlistinfo took 94 milliseconds Mar 14 21:50:14 volumio volumio[12324]: info: sendMpdCommand playlistinfo took 94 milliseconds Mar 14 21:50:14 volumio volumio[12324]: verbose: ControllerMpd::parseTrackInfo Mar 14 21:50:14 volumio volumio[12324]: info: ControllerMpd::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::servicePushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132842,"duration":309,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"So Far Away","artist":"Dire Straits","album":"Brothers In Arms (Remastered)","uri":"NAS/istoreOS/1985 Brothers In Arms (Remastered)/01 So Far Away.m4a","trackType":"m4a"} Mar 14 21:50:14 volumio volumio[12324]: verbose: CURRENT POSITION 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState stateService play Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState currentStatus play Mar 14 21:50:14 volumio volumio[12324]: info: Received an update from plugin. extracting info from payload Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: ControllerMpd::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::servicePushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: verbose: STATE SERVICE {"status":"play","position":0,"seek":132842,"duration":309,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"So Far Away","artist":"Dire Straits","album":"Brothers In Arms (Remastered)","uri":"NAS/istoreOS/1985 Brothers In Arms (Remastered)/01 So Far Away.m4a","trackType":"m4a"} Mar 14 21:50:14 volumio volumio[12324]: verbose: CURRENT POSITION 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState stateService play Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::syncState currentStatus play Mar 14 21:50:14 volumio volumio[12324]: info: Received an update from plugin. extracting info from payload Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: CoreStateMachine::pushState Mar 14 21:50:14 volumio volumio[12324]: info: CorePlayQueue::getTrack 0 Mar 14 21:50:14 volumio volumio[12324]: info: CoreCommandRouter::volumioPushState Mar 14 21:50:14 volumio volumio[12324]: info: ------------------------------ 846ms Mar 14 21:50:14 volumio volumio[12324]: info: ------------------------------ 846ms Mar 14 21:50:17 volumio volumio[12324]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 14 21:50:17 volumio volumio[12324]: Error: connect ETIMEDOUT 128.242.245.189:443 Mar 14 21:50:17 volumio volumio[12324]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 14 21:50:17 volumio volumio[12324]: errno: -110, Mar 14 21:50:17 volumio volumio[12324]: code: 'ETIMEDOUT', Mar 14 21:50:17 volumio volumio[12324]: syscall: 'connect', Mar 14 21:50:17 volumio volumio[12324]: address: '128.242.245.189', Mar 14 21:50:17 volumio volumio[12324]: port: 443 Mar 14 21:50:17 volumio volumio[12324]: } Mar 14 21:50:17 volumio volumio[12324]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 14 21:50:17 volumio sudo[13742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-14 21:49 Mar 14 21:50:17 volumio sudo[13742]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"