-- Logs begin at Fri 2025-05-30 08:48:30 MSK, end at Fri 2025-05-30 08:55:04 MSK. -- May 30 08:54:02 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState May 30 08:54:03 volumio volumio[1128]: info: CoreCommandRouter::volumioPlay May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::play index undefined May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:54:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 3 May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::startPlaybackTimer May 30 08:54:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 3 May 30 08:54:03 volumio volumio[1128]: info: [1748584443899] ControllerUPNPBrowser::clearAddPlayTrack May 30 08:54:03 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:54:03 volumio mpd[1483]: player: played "http://192.168.0.1:8200/MediaItems/153.mp3" May 30 08:54:03 volumio volumio[1128]: info: sendMpdCommand stop took 1 milliseconds May 30 08:54:03 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:54:03 volumio volumio[1128]: info: May 30 08:54:03 volumio volumio[1128]: ---------------------------- MPD announces state update: player May 30 08:54:03 volumio volumio[1128]: info: May 30 08:54:03 volumio volumio[1128]: ---------------------------- MPD announces system playlist update May 30 08:54:03 volumio volumio[1128]: info: Ignoring MPD Status Update May 30 08:54:03 volumio volumio[1128]: info: ControllerMpd::getState May 30 08:54:03 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status May 30 08:54:03 volumio volumio[1128]: info: sendMpdCommand clear took 0 milliseconds May 30 08:54:03 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.1:8200/MediaItems/153.mp3" May 30 08:54:03 volumio volumio[1128]: error: updateQueue error: null May 30 08:54:03 volumio volumio[1128]: info: sendMpdCommand status took 0 milliseconds May 30 08:54:03 volumio volumio[1128]: info: ------------------------------ 1ms May 30 08:54:03 volumio volumio[1128]: verbose: ControllerMpd::parseState May 30 08:54:03 volumio volumio[1128]: info: ControllerMpd::pushState May 30 08:54:03 volumio volumio[1128]: info: CoreCommandRouter::servicePushState May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::pushState May 30 08:54:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 3 May 30 08:54:03 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:54:03 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState May 30 08:54:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 3 May 30 08:54:03 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 30 08:54:03 volumio volumio[1128]: verbose: CURRENT POSITION 3 May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop May 30 08:54:03 volumio mpd[1483]: exception: No such playlist May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::pushState May 30 08:54:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 3 May 30 08:54:03 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState May 30 08:54:03 volumio volumio[1128]: info: No code May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::pushState May 30 08:54:03 volumio volumio[1128]: info: CorePlayQueue::getTrack 3 May 30 08:54:03 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState May 30 08:54:03 volumio volumio[1128]: info: ------------------------------ 7ms May 30 08:54:03 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.1:8200/MediaItems/153.mp3" May 30 08:54:03 volumio volumio[1128]: info: May 30 08:54:03 volumio volumio[1128]: ---------------------------- MPD announces system playlist update May 30 08:54:03 volumio volumio[1128]: info: Ignoring MPD Status Update May 30 08:54:03 volumio volumio[1128]: info: sendMpdCommand add "http://192.168.0.1:8200/MediaItems/153.mp3" took 2 milliseconds May 30 08:54:03 volumio volumio[1128]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:54:03 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand play May 30 08:54:03 volumio mpd[1483]: jack_output: Cannot connect to server socket err = No such file or directory May 30 08:54:03 volumio mpd[1483]: jack_output: Cannot connect to server request channel May 30 08:54:03 volumio volumio[1128]: info: May 30 08:54:03 volumio volumio[1128]: ---------------------------- MPD announces state update: player May 30 08:54:03 volumio volumio[1128]: info: ------------------------------ 5ms May 30 08:54:03 volumio volumio[1128]: info: sendMpdCommand play took 5 milliseconds May 30 08:54:03 volumio volumio[1128]: info: ControllerMpd::getState May 30 08:54:03 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status May 30 08:54:03 volumio mpd[1483]: jack_output: jack server is not running or cannot be started May 30 08:54:03 volumio mpd[1483]: jack_output: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock May 30 08:54:03 volumio mpd[1483]: exception: Failed to enable output "default detected output" (jack) May 30 08:54:03 volumio mpd[1483]: exception: nested: Failed to connect to JACK server, status=17 May 30 08:54:03 volumio mpd[1483]: exception: Failed to enable output "default detected output" (jack) May 30 08:54:03 volumio mpd[1483]: exception: nested: Failed to connect to JACK server, status=17 May 30 08:54:03 volumio mpd[1483]: player: problems opening audio device while playing "http://192.168.0.1:8200/MediaItems/153.mp3" May 30 08:54:04 volumio volumio[1128]: info: May 30 08:54:04 volumio volumio[1128]: ---------------------------- MPD announces state update: player May 30 08:54:04 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:54:04 volumio volumio[1128]: info: sendMpdCommand status took 588 milliseconds May 30 08:54:04 volumio volumio[1128]: info: ControllerMpd::getState May 30 08:54:04 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status May 30 08:54:04 volumio volumio[1128]: verbose: ControllerMpd::parseState May 30 08:54:04 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:54:04 volumio volumio[1128]: info: sendMpdCommand clearerror took 1 milliseconds May 30 08:54:04 volumio volumio[1128]: info: sendMpdCommand status took 1 milliseconds May 30 08:54:04 volumio volumio[1128]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 08:54:04 volumio volumio[1128]: verbose: ControllerMpd::parseState May 30 08:54:04 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:54:04 volumio volumio[1128]: verbose: ControllerMpd::parseTrackInfo May 30 08:54:04 volumio volumio[1128]: info: ControllerMpd::pushState May 30 08:54:04 volumio volumio[1128]: info: CoreCommandRouter::servicePushState May 30 08:54:04 volumio volumio[1128]: info: CorePlayQueue::getTrack 3 May 30 08:54:04 volumio volumio[1128]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":93,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"153.mp3","artist":null,"album":null,"uri":"http://192.168.0.1:8200/MediaItems/153.mp3","trackType":"mp3"} May 30 08:54:04 volumio volumio[1128]: verbose: CURRENT POSITION 3 May 30 08:54:04 volumio volumio[1128]: info: CoreStateMachine::syncState stateService pause May 30 08:54:04 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop May 30 08:54:04 volumio volumio[1128]: info: CoreStateMachine::pushState May 30 08:54:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:54:04 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState May 30 08:54:04 volumio volumio[1128]: info: ------------------------------ 591ms May 30 08:54:04 volumio volumio[1128]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 08:54:04 volumio volumio[1128]: verbose: ControllerMpd::parseTrackInfo May 30 08:54:04 volumio volumio[1128]: info: ControllerMpd::pushState May 30 08:54:04 volumio volumio[1128]: info: CoreCommandRouter::servicePushState May 30 08:54:04 volumio volumio[1128]: info: CorePlayQueue::getTrack 3 May 30 08:54:04 volumio volumio[1128]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":93,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"153.mp3","artist":null,"album":null,"uri":"http://192.168.0.1:8200/MediaItems/153.mp3","trackType":"mp3"} May 30 08:54:04 volumio volumio[1128]: verbose: CURRENT POSITION 3 May 30 08:54:04 volumio volumio[1128]: info: CoreStateMachine::syncState stateService pause May 30 08:54:04 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop May 30 08:54:04 volumio volumio[1128]: info: CoreStateMachine::pushState May 30 08:54:04 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState May 30 08:54:04 volumio volumio[1128]: info: ------------------------------ 7ms May 30 08:54:14 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 08:54:29 volumio volumio[1128]: info: Retrieving Cloud Streaming UI May 30 08:54:29 volumio volumio[1128]: info: Getting Tidal Cloud Configuration May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 08:54:29 volumio volumio[1128]: info: Getting Qobuz Cloud Configuration May 30 08:54:29 volumio volumio[1128]: info: Asking plugin for UI Config May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 08:54:29 volumio volumio[1128]: info: Getting Spotify Cloud Configuration May 30 08:54:29 volumio volumio[1128]: info: Asking plugin for UI Config May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 08:54:29 volumio volumio[1128]: info: Saving Spotify Acccount May 30 08:54:29 volumio volumio[1128]: info: Got Tidal Cloud Configuration May 30 08:54:29 volumio volumio[1128]: info: Got it May 30 08:54:29 volumio volumio[1128]: info: Got it May 30 08:54:29 volumio volumio[1128]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 08:54:29 volumio volumio[1128]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::volumioGetBrowseSources May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::volumioGetBrowseSources May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::volumioGetBrowseSources May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:54:29 volumio mpd[1483]: exception: No database May 30 08:54:29 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 30 08:54:33 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:54:33 volumio mpd[1483]: exception: No database May 30 08:54:35 volumio ntpd[1117]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 30 08:54:37 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:54:37 volumio mpd[1483]: exception: No database May 30 08:54:41 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:54:41 volumio mpd[1483]: exception: No database May 30 08:54:43 volumio volumio[1128]: info: Enabling MyMusic plugin bluetooth May 30 08:54:43 volumio volumio[1128]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth May 30 08:54:43 volumio volumio[1128]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling May 30 08:54:43 volumio volumio[1128]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling May 30 08:54:43 volumio volumio[1128]: error: Could not Enable MyMusic Plugin: Error May 30 08:54:45 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:54:45 volumio mpd[1483]: exception: No database May 30 08:54:49 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:54:49 volumio mpd[1483]: exception: No database May 30 08:54:53 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:54:53 volumio mpd[1483]: exception: No database May 30 08:54:57 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:54:57 volumio mpd[1483]: exception: No database May 30 08:55:01 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 08:55:01 volumio mpd[1483]: exception: No database May 30 08:55:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 30 08:55:04 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 30 08:55:04 volumio volumio[1128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 08:55:04 volumio volumio[1128]: Error: getaddrinfo -3007 May 30 08:55:04 volumio volumio[1128]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) May 30 08:55:04 volumio volumio[1128]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) May 30 08:55:04 volumio volumio[1128]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { May 30 08:55:04 volumio volumio[1128]: code: -3007, May 30 08:55:04 volumio volumio[1128]: errno: -3007, May 30 08:55:04 volumio volumio[1128]: syscall: 'getaddrinfo' May 30 08:55:04 volumio volumio[1128]: } May 30 08:55:04 volumio volumio[1128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 08:55:04 volumio sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-30 08:54 May 30 08:55:04 volumio sudo[2129]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:09:51 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2fe933ffb50e198f3245fc325fd10b17"