-- Logs begin at Sun 2024-12-01 08:20:05 CST, end at Sun 2024-12-01 09:07:05 CST. -- Dec 01 09:06:06 volumio volumio[1333]: info: MyVolumio login type: Token Dec 01 09:06:36 volumio volumio[1333]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 01 09:06:36 volumio volumio[1333]: info: Retrying Login Due to Network Error Dec 01 09:06:38 volumio sshd[4644]: pam_unix(sshd:session): session closed for user volumio Dec 01 09:06:38 volumio systemd[1]: session-1.scope: Succeeded. Dec 01 09:06:38 volumio systemd-logind[727]: Session 1 logged out. Waiting for processes to exit. Dec 01 09:06:38 volumio systemd-logind[727]: Removed session 1. Dec 01 09:06:46 volumio volumio[1333]: info: MyVolumio login type: Token Dec 01 09:06:48 volumio systemd[1]: Stopping User Manager for UID 1000... Dec 01 09:06:49 volumio systemd[4667]: Stopped target Default. Dec 01 09:06:49 volumio systemd[4667]: Stopped target Basic System. Dec 01 09:06:49 volumio systemd[4667]: Stopped target Sockets. Dec 01 09:06:49 volumio systemd[4667]: gpg-agent.socket: Succeeded. Dec 01 09:06:49 volumio systemd[4667]: Closed GnuPG cryptographic agent and passphrase cache. Dec 01 09:06:49 volumio systemd[4667]: gpg-agent-extra.socket: Succeeded. Dec 01 09:06:49 volumio systemd[4667]: Closed GnuPG cryptographic agent and passphrase cache (restricted). Dec 01 09:06:49 volumio systemd[4667]: gpg-agent-browser.socket: Succeeded. Dec 01 09:06:49 volumio systemd[4667]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 01 09:06:49 volumio systemd[4667]: gpg-agent-ssh.socket: Succeeded. Dec 01 09:06:49 volumio systemd[4667]: Closed GnuPG cryptographic agent (ssh-agent emulation). Dec 01 09:06:49 volumio systemd[4667]: dbus.socket: Succeeded. Dec 01 09:06:49 volumio systemd[4667]: Closed D-Bus User Message Bus Socket. Dec 01 09:06:49 volumio systemd[4667]: pulseaudio.socket: Succeeded. Dec 01 09:06:49 volumio systemd[4667]: Closed Sound System. Dec 01 09:06:49 volumio systemd[4667]: Stopped target Paths. Dec 01 09:06:49 volumio systemd[4667]: Stopped target Timers. Dec 01 09:06:49 volumio systemd[4667]: dirmngr.socket: Succeeded. Dec 01 09:06:49 volumio systemd[4667]: Closed GnuPG network certificate management daemon. Dec 01 09:06:49 volumio systemd[4667]: Reached target Shutdown. Dec 01 09:06:49 volumio systemd[4667]: systemd-exit.service: Succeeded. Dec 01 09:06:49 volumio systemd[4667]: Started Exit the Session. Dec 01 09:06:49 volumio systemd[4667]: Reached target Exit the Session. Dec 01 09:06:49 volumio systemd[4668]: pam_unix(systemd-user:session): session closed for user volumio Dec 01 09:06:49 volumio systemd[1]: user@1000.service: Succeeded. Dec 01 09:06:49 volumio systemd[1]: Stopped User Manager for UID 1000. Dec 01 09:06:49 volumio systemd[1]: Stopping User Runtime Directory /run/user/1000... Dec 01 09:06:49 volumio systemd[1]: run-user-1000.mount: Succeeded. Dec 01 09:06:49 volumio systemd[1]: user-runtime-dir@1000.service: Succeeded. Dec 01 09:06:49 volumio systemd[1]: Stopped User Runtime Directory /run/user/1000. Dec 01 09:06:49 volumio systemd[1]: Removed slice User Slice of UID 1000. Dec 01 09:06:49 volumio volumio[1333]: info: CoreCommandRouter::volumioGetState Dec 01 09:06:49 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:06:57 volumio volumio[1333]: info: Retrieving Cloud Streaming UI Dec 01 09:06:57 volumio volumio[1333]: info: Getting Tidal Cloud Configuration Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:06:57 volumio volumio[1333]: info: Getting Qobuz Cloud Configuration Dec 01 09:06:57 volumio volumio[1333]: info: Asking plugin for UI Config Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:06:57 volumio volumio[1333]: info: Getting Spotify Cloud Configuration Dec 01 09:06:57 volumio volumio[1333]: info: Asking plugin for UI Config Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:06:57 volumio volumio[1333]: info: Saving Spotify Acccount Dec 01 09:06:57 volumio volumio[1333]: info: Got Tidal Cloud Configuration Dec 01 09:06:57 volumio volumio[1333]: info: Got it Dec 01 09:06:57 volumio volumio[1333]: info: Got it Dec 01 09:06:57 volumio volumio[1333]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:06:57 volumio volumio[1333]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 09:06:57 volumio volumio[1333]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 09:06:58 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 09:06:58 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 01 09:07:01 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand update Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:07:02 volumio volumio[1333]: info: Dec 01 09:07:02 volumio volumio[1333]: ---------------------------- MPD announces state update: update Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand update took 12 milliseconds Dec 01 09:07:02 volumio volumio[1333]: info: ControllerMpd::getState Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand status took 13 milliseconds Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::parseState Dec 01 09:07:02 volumio volumio[1333]: info: Command Router : Notfying DB Updatetrue Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::Close All Modals sent Dec 01 09:07:02 volumio volumio[1333]: info: MPD Database updated - AlbumList cache refreshed Dec 01 09:07:02 volumio volumio[1333]: info: Dec 01 09:07:02 volumio volumio[1333]: ---------------------------- MPD announces state update: database Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:07:02 volumio volumio[1333]: info: Dec 01 09:07:02 volumio volumio[1333]: ---------------------------- MPD announces state update: update Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand status took 175 milliseconds Dec 01 09:07:02 volumio volumio[1333]: info: ControllerMpd::getState Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:07:02 volumio volumio[1333]: info: ControllerMpd::getState Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::parseState Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand status took 48 milliseconds Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand status took 38 milliseconds Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand status took 35 milliseconds Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::parseState Dec 01 09:07:02 volumio volumio[1333]: info: Command Router : Notfying DB Updatefalse Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::Close All Modals sent Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::parseState Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::parseState Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::parseTrackInfo Dec 01 09:07:02 volumio volumio[1333]: info: ControllerMpd::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::servicePushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: verbose: STATE SERVICE {"status":"play","position":0,"seek":134458,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1072 Kbps","isStreaming":false,"title":"未来へ","artist":"Kiroro","album":"長い間 ～キロロの森～","uri":"INTERNAL/music/Kiroro-未来へ.flac","trackType":"flac"} Dec 01 09:07:02 volumio volumio[1333]: verbose: CURRENT POSITION 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::syncState stateService play Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::syncState currentStatus play Dec 01 09:07:02 volumio volumio[1333]: info: Received an update from plugin. extracting info from payload Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::volumioPushState Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::volumioPushState Dec 01 09:07:02 volumio volumio[1333]: info: ------------------------------ 462ms Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand playlistinfo took 164 milliseconds Dec 01 09:07:02 volumio volumio[1333]: info: sendMpdCommand playlistinfo took 165 milliseconds Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::parseTrackInfo Dec 01 09:07:02 volumio volumio[1333]: verbose: ControllerMpd::parseTrackInfo Dec 01 09:07:02 volumio volumio[1333]: info: ControllerMpd::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::servicePushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: verbose: STATE SERVICE {"status":"play","position":0,"seek":134640,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"未来へ","artist":"Kiroro","album":"長い間 ～キロロの森～","uri":"INTERNAL/music/Kiroro-未来へ.flac","trackType":"flac"} Dec 01 09:07:02 volumio volumio[1333]: verbose: CURRENT POSITION 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::syncState stateService play Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::syncState currentStatus play Dec 01 09:07:02 volumio volumio[1333]: info: Received an update from plugin. extracting info from payload Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::volumioPushState Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::volumioPushState Dec 01 09:07:02 volumio volumio[1333]: info: ControllerMpd::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::servicePushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: verbose: STATE SERVICE {"status":"play","position":0,"seek":134640,"duration":333,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"未来へ","artist":"Kiroro","album":"長い間 ～キロロの森～","uri":"INTERNAL/music/Kiroro-未来へ.flac","trackType":"flac"} Dec 01 09:07:02 volumio volumio[1333]: verbose: CURRENT POSITION 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::syncState stateService play Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::syncState currentStatus play Dec 01 09:07:02 volumio volumio[1333]: info: Received an update from plugin. extracting info from payload Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::volumioPushState Dec 01 09:07:02 volumio volumio[1333]: info: CoreStateMachine::pushState Dec 01 09:07:02 volumio volumio[1333]: info: CorePlayQueue::getTrack 11 Dec 01 09:07:02 volumio volumio[1333]: info: CoreCommandRouter::volumioPushState Dec 01 09:07:03 volumio volumio[1333]: info: ------------------------------ 514ms Dec 01 09:07:03 volumio volumio[1333]: info: ------------------------------ 515ms Dec 01 09:07:04 volumio volumio[1333]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 09:07:04 volumio volumio[1333]: Error: connect ETIMEDOUT 69.63.186.30:443 Dec 01 09:07:04 volumio volumio[1333]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 01 09:07:04 volumio volumio[1333]: errno: -110, Dec 01 09:07:04 volumio volumio[1333]: code: 'ETIMEDOUT', Dec 01 09:07:04 volumio volumio[1333]: syscall: 'connect', Dec 01 09:07:04 volumio volumio[1333]: address: '69.63.186.30', Dec 01 09:07:04 volumio volumio[1333]: port: 443 Dec 01 09:07:04 volumio volumio[1333]: } Dec 01 09:07:04 volumio volumio[1333]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 09:07:05 volumio sudo[16781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 09:06 Dec 01 09:07:05 volumio sudo[16781]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"