-- Logs begin at Thu 2019-02-14 14:11:59 +04, end at Tue 2026-05-26 12:50:58 +04. -- May 26 12:50:01 9th CRON[22530]: pam_unix(cron:session): session opened for user root by (uid=0) May 26 12:50:01 9th CRON[22531]: (root) CMD (/root/sycfm/bin/mp-sw.sh 2) May 26 12:50:22 9th volumio[18272]: info: CoreCommandRouter::volumioGetState May 26 12:50:22 9th volumio[18272]: info: CorePlayQueue::getTrack 1 May 26 12:50:22 9th volumio[18272]: info: CoreCommandRouter::volumioGetQueue May 26 12:50:22 9th volumio[18272]: info: CoreStateMachine::getQueue May 26 12:50:22 9th volumio[18272]: info: CorePlayQueue::getQueue May 26 12:50:24 9th volumio[18272]: info: CoreCommandRouter::volumioGetQueue May 26 12:50:24 9th volumio[18272]: info: CoreStateMachine::getQueue May 26 12:50:24 9th volumio[18272]: info: CorePlayQueue::getQueue May 26 12:50:25 9th volumio[18272]: info: CoreCommandRouter::volumioGetState May 26 12:50:25 9th volumio[18272]: info: CorePlayQueue::getTrack 1 May 26 12:50:25 9th volumio[18272]: info: CoreCommandRouter::volumioGetState May 26 12:50:25 9th volumio[18272]: info: CorePlayQueue::getTrack 1 May 26 12:50:25 9th volumio[18272]: info: CoreCommandRouter::volumioGetState May 26 12:50:25 9th volumio[18272]: info: CorePlayQueue::getTrack 1 May 26 12:50:34 9th volumio[18272]: info: CorePlayQueue::getTrack 1 May 26 12:50:34 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:34 9th volumio[18272]: info: Prefetching next song May 26 12:50:34 9th volumio[18272]: info: DOING PREFETCH IN MPD May 26 12:50:34 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/2 Coolbeats/Infatuated - Miakie.mp3" May 26 12:50:34 9th volumio[18272]: info: May 26 12:50:34 9th volumio[18272]: ---------------------------- MPD announces system playlist update May 26 12:50:34 9th volumio[18272]: info: Ignoring MPD Status Update May 26 12:50:34 9th volumio[18272]: info: sendMpdCommand add "INTERNAL/2 Coolbeats/Infatuated - Miakie.mp3" took 4 milliseconds May 26 12:50:34 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand consume 1 May 26 12:50:34 9th volumio[18272]: info: May 26 12:50:34 9th volumio[18272]: ---------------------------- MPD announces system playlist update May 26 12:50:34 9th volumio[18272]: info: Ignoring MPD Status Update May 26 12:50:34 9th volumio[18272]: info: May 26 12:50:34 9th volumio[18272]: ---------------------------- MPD announces system playlist update May 26 12:50:34 9th volumio[18272]: info: Ignoring MPD Status Update May 26 12:50:34 9th volumio[18272]: info: ------------------------------ 14ms May 26 12:50:34 9th volumio[18272]: info: sendMpdCommand consume 1 took 13 milliseconds May 26 12:50:34 9th volumio[18272]: info: ------------------------------ 11ms May 26 12:50:34 9th volumio[18272]: info: ------------------------------ 10ms May 26 12:50:38 9th volumio[18272]: info: CoreStateMachine::startPlaybackTimer May 26 12:50:38 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:39 9th cron[627]: 2026-05-26 12:50:39 1wRnUl-00066P-2a Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=118 May 26 12:50:39 9th cron[627]: 2026-05-26 12:50:39 1wRnUl-00066P-2a <= root@volumio U=root P=local S=2872 May 26 12:50:39 9th cron[627]: 2026-05-26 12:50:39 1wRnUl-00066P-2a Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=118 May 26 12:50:39 9th cron[627]: exim: could not open panic log - aborting: see message(s) above May 26 12:50:39 9th exim[23461]: 2026-05-26 12:50:39 1wRnUl-00066P-2a <= root@volumio U=root P=local S=2872 May 26 12:50:39 9th exim[23461]: 2026-05-26 12:50:39 1wRnUl-00066P-2a Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=118 May 26 12:50:39 9th exim[23461]: exim: could not open panic log - aborting: see message(s) above May 26 12:50:39 9th CRON[22530]: (root) MAIL (mailed 2326 bytes of output but got status 0x0001 from MTA ) May 26 12:50:39 9th CRON[22530]: pam_unix(cron:session): session closed for user root May 26 12:50:39 9th volumio[18272]: info: May 26 12:50:39 9th volumio[18272]: ---------------------------- MPD announces system playlist update May 26 12:50:39 9th volumio[18272]: info: Ignoring MPD Status Update May 26 12:50:39 9th volumio[18272]: info: May 26 12:50:39 9th volumio[18272]: ---------------------------- MPD announces state update: player May 26 12:50:39 9th volumio[18272]: info: ControllerMpd::getState May 26 12:50:39 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand status May 26 12:50:39 9th volumio[18272]: info: May 26 12:50:39 9th volumio[18272]: ---------------------------- MPD announces system playlist update May 26 12:50:39 9th volumio[18272]: info: Ignoring MPD Status Update May 26 12:50:39 9th volumio[18272]: info: May 26 12:50:39 9th volumio[18272]: ---------------------------- MPD announces state update: player May 26 12:50:39 9th volumio[18272]: info: ControllerMpd::getState May 26 12:50:39 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand status May 26 12:50:39 9th volumio[18272]: info: ------------------------------ 10ms May 26 12:50:39 9th volumio[18272]: info: May 26 12:50:39 9th volumio[18272]: ---------------------------- MPD announces system playlist update May 26 12:50:39 9th volumio[18272]: info: Ignoring MPD Status Update May 26 12:50:39 9th volumio[18272]: info: May 26 12:50:39 9th volumio[18272]: ---------------------------- MPD announces state update: player May 26 12:50:39 9th volumio[18272]: info: ControllerMpd::getState May 26 12:50:39 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand status May 26 12:50:39 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:39 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:39 9th volumio[18272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 12:50:39 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand status took 898 milliseconds May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:50:40 9th volumio[18272]: info: May 26 12:50:40 9th volumio[18272]: ---------------------------- MPD announces state update: player May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::getState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand status May 26 12:50:40 9th volumio[18272]: info: ------------------------------ 908ms May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand status took 906 milliseconds May 26 12:50:40 9th volumio[18272]: info: ------------------------------ 904ms May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand status took 901 milliseconds May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand playlistinfo took 11 milliseconds May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseTrackInfo May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::pushState May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::servicePushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Infatuated","artist":"Miakie","album":"Infatuated","uri":"INTERNAL/2 Coolbeats/Infatuated - Miakie.mp3","trackType":"mp3"} May 26 12:50:40 9th volumio[18272]: verbose: CURRENT POSITION 2 May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState stateService play May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState currentStatus play May 26 12:50:40 9th volumio[18272]: info: Received an update from plugin. extracting info from payload May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: ------------------------------ 945ms May 26 12:50:40 9th volumio[18272]: info: May 26 12:50:40 9th volumio[18272]: ---------------------------- MPD announces state update: player May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::getState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand status May 26 12:50:40 9th volumio[18272]: info: May 26 12:50:40 9th volumio[18272]: ---------------------------- MPD announces state update: player May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::getState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand status May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand status took 43 milliseconds May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand playlistinfo took 39 milliseconds May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand playlistinfo took 39 milliseconds May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand status took 7 milliseconds May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand status took 5 milliseconds May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseTrackInfo May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseTrackInfo May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseState May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::pushState May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::servicePushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Infatuated","artist":"Miakie","album":"Infatuated","uri":"INTERNAL/2 Coolbeats/Infatuated - Miakie.mp3","trackType":"mp3"} May 26 12:50:40 9th volumio[18272]: verbose: CURRENT POSITION 2 May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState stateService play May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState currentStatus play May 26 12:50:40 9th volumio[18272]: info: Received an update from plugin. extracting info from payload May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::pushState May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::servicePushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Infatuated","artist":"Miakie","album":"Infatuated","uri":"INTERNAL/2 Coolbeats/Infatuated - Miakie.mp3","trackType":"mp3"} May 26 12:50:40 9th volumio[18272]: verbose: CURRENT POSITION 2 May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState stateService play May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState currentStatus play May 26 12:50:40 9th volumio[18272]: info: Received an update from plugin. extracting info from payload May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: ------------------------------ 1011ms May 26 12:50:40 9th volumio[18272]: info: ------------------------------ 1008ms May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand playlistinfo took 75 milliseconds May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand playlistinfo took 74 milliseconds May 26 12:50:40 9th volumio[18272]: info: sendMpdCommand playlistinfo took 73 milliseconds May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseTrackInfo May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseTrackInfo May 26 12:50:40 9th volumio[18272]: verbose: ControllerMpd::parseTrackInfo May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::pushState May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::servicePushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Infatuated","artist":"Miakie","album":"Infatuated","uri":"INTERNAL/2 Coolbeats/Infatuated - Miakie.mp3","trackType":"mp3"} May 26 12:50:40 9th volumio[18272]: verbose: CURRENT POSITION 2 May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState stateService play May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState currentStatus play May 26 12:50:40 9th volumio[18272]: info: Received an update from plugin. extracting info from payload May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::pushState May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::servicePushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Infatuated","artist":"Miakie","album":"Infatuated","uri":"INTERNAL/2 Coolbeats/Infatuated - Miakie.mp3","trackType":"mp3"} May 26 12:50:40 9th volumio[18272]: verbose: CURRENT POSITION 2 May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState stateService play May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState currentStatus play May 26 12:50:40 9th volumio[18272]: info: Received an update from plugin. extracting info from payload May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: ControllerMpd::pushState May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::servicePushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":240,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Infatuated","artist":"Miakie","album":"Infatuated","uri":"INTERNAL/2 Coolbeats/Infatuated - Miakie.mp3","trackType":"mp3"} May 26 12:50:40 9th volumio[18272]: verbose: CURRENT POSITION 2 May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState stateService play May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::syncState currentStatus play May 26 12:50:40 9th volumio[18272]: info: Received an update from plugin. extracting info from payload May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: CoreStateMachine::pushState May 26 12:50:40 9th volumio[18272]: info: CorePlayQueue::getTrack 2 May 26 12:50:40 9th volumio[18272]: info: CoreCommandRouter::volumioPushState May 26 12:50:40 9th volumio[18272]: info: ------------------------------ 210ms May 26 12:50:40 9th volumio[18272]: info: ------------------------------ 169ms May 26 12:50:40 9th volumio[18272]: info: ------------------------------ 167ms May 26 12:50:57 9th volumio[18272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 12:50:57 9th volumio[18272]: Error: connect ECONNREFUSED 151.101.194.79:443 May 26 12:50:57 9th volumio[18272]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 26 12:50:57 9th volumio[18272]: errno: -111, May 26 12:50:57 9th volumio[18272]: code: 'ECONNREFUSED', May 26 12:50:57 9th volumio[18272]: syscall: 'connect', May 26 12:50:57 9th volumio[18272]: address: '151.101.194.79', May 26 12:50:57 9th volumio[18272]: port: 443 May 26 12:50:57 9th volumio[18272]: } May 26 12:50:57 9th volumio[18272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 12:50:58 9th sudo[23514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-26 12:49 May 26 12:50:58 9th sudo[23514]: 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="eb7109a0318f43be1cdb19572cb80f7f163a915d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Чт 01 фев 2024 20:49:33 MSK" VOLUMIO_VERSION="3.0" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="22506d91c2deba7d4d5e58d82babad9f"