-- Logs begin at Sat 2025-06-28 13:17:01 +04, end at Sat 2025-06-28 14:44:46 +04. -- Jun 28 14:43:17 spa-main volumio[1176]: info: CorePlayQueue::getTrack 29 Jun 28 14:43:17 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:17 spa-main volumio[1176]: info: Prefetching next song Jun 28 14:43:17 spa-main volumio[1176]: info: DOING PREFETCH IN MPD Jun 28 14:43:17 spa-main volumio[1176]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/3 Gentle Guitar/I Wanna Be Yours Trevor Potts.mp3" Jun 28 14:43:17 spa-main volumio[1176]: info: sendMpdCommand add "INTERNAL/3 Gentle Guitar/I Wanna Be Yours Trevor Potts.mp3" took 1 milliseconds Jun 28 14:43:17 spa-main volumio[1176]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 28 14:43:17 spa-main volumio[1176]: info: Jun 28 14:43:17 spa-main volumio[1176]: ---------------------------- MPD announces system playlist update Jun 28 14:43:17 spa-main volumio[1176]: info: Ignoring MPD Status Update Jun 28 14:43:17 spa-main volumio[1176]: info: sendMpdCommand consume 1 took 2 milliseconds Jun 28 14:43:17 spa-main volumio[1176]: info: Jun 28 14:43:17 spa-main volumio[1176]: ---------------------------- MPD announces system playlist update Jun 28 14:43:17 spa-main volumio[1176]: info: Ignoring MPD Status Update Jun 28 14:43:17 spa-main volumio[1176]: info: Jun 28 14:43:17 spa-main volumio[1176]: ---------------------------- MPD announces system playlist update Jun 28 14:43:17 spa-main volumio[1176]: info: Ignoring MPD Status Update Jun 28 14:43:17 spa-main volumio[1176]: info: ------------------------------ 7ms Jun 28 14:43:17 spa-main volumio[1176]: info: ------------------------------ 4ms Jun 28 14:43:17 spa-main volumio[1176]: info: ------------------------------ 4ms Jun 28 14:43:21 spa-main volumio[1176]: info: CoreStateMachine::startPlaybackTimer Jun 28 14:43:21 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: info: Jun 28 14:43:22 spa-main volumio[1176]: ---------------------------- MPD announces system playlist update Jun 28 14:43:22 spa-main volumio[1176]: info: Ignoring MPD Status Update Jun 28 14:43:22 spa-main volumio[1176]: info: Jun 28 14:43:22 spa-main volumio[1176]: ---------------------------- MPD announces state update: player Jun 28 14:43:22 spa-main volumio[1176]: info: ControllerMpd::getState Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jun 28 14:43:22 spa-main volumio[1176]: info: Jun 28 14:43:22 spa-main volumio[1176]: ---------------------------- MPD announces system playlist update Jun 28 14:43:22 spa-main volumio[1176]: info: Ignoring MPD Status Update Jun 28 14:43:22 spa-main volumio[1176]: info: Jun 28 14:43:22 spa-main volumio[1176]: ---------------------------- MPD announces state update: player Jun 28 14:43:22 spa-main volumio[1176]: info: ControllerMpd::getState Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jun 28 14:43:22 spa-main volumio[1176]: info: Jun 28 14:43:22 spa-main volumio[1176]: ---------------------------- MPD announces system playlist update Jun 28 14:43:22 spa-main volumio[1176]: info: Ignoring MPD Status Update Jun 28 14:43:22 spa-main volumio[1176]: info: Jun 28 14:43:22 spa-main volumio[1176]: ---------------------------- MPD announces state update: player Jun 28 14:43:22 spa-main volumio[1176]: info: ControllerMpd::getState Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Jun 28 14:43:22 spa-main volumio[1176]: info: ------------------------------ 10ms Jun 28 14:43:22 spa-main volumio[1176]: info: sendMpdCommand status took 9 milliseconds Jun 28 14:43:22 spa-main volumio[1176]: info: ------------------------------ 8ms Jun 28 14:43:22 spa-main volumio[1176]: info: sendMpdCommand status took 6 milliseconds Jun 28 14:43:22 spa-main volumio[1176]: info: ------------------------------ 6ms Jun 28 14:43:22 spa-main volumio[1176]: info: sendMpdCommand status took 5 milliseconds Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::parseState Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::parseState Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::parseState Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 14:43:22 spa-main volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 28 14:43:22 spa-main volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 28 14:43:22 spa-main volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jun 28 14:43:22 spa-main volumio[1176]: verbose: ControllerMpd::parseTrackInfo Jun 28 14:43:22 spa-main volumio[1176]: info: ControllerMpd::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::servicePushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"I Wanna Be Yours","artist":"Trevor Potts","album":"I Wanna Be Yours","uri":"INTERNAL/3 Gentle Guitar/I Wanna Be Yours Trevor Potts.mp3","trackType":"mp3"} Jun 28 14:43:22 spa-main volumio[1176]: verbose: CURRENT POSITION 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::syncState stateService play Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::syncState currentStatus play Jun 28 14:43:22 spa-main volumio[1176]: info: Received an update from plugin. extracting info from payload Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 28 14:43:22 spa-main volumio[1176]: info: ControllerMpd::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::servicePushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"I Wanna Be Yours","artist":"Trevor Potts","album":"I Wanna Be Yours","uri":"INTERNAL/3 Gentle Guitar/I Wanna Be Yours Trevor Potts.mp3","trackType":"mp3"} Jun 28 14:43:22 spa-main volumio[1176]: verbose: CURRENT POSITION 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::syncState stateService play Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::syncState currentStatus play Jun 28 14:43:22 spa-main volumio[1176]: info: Received an update from plugin. extracting info from payload Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 28 14:43:22 spa-main volumio[1176]: info: ControllerMpd::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::servicePushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"I Wanna Be Yours","artist":"Trevor Potts","album":"I Wanna Be Yours","uri":"INTERNAL/3 Gentle Guitar/I Wanna Be Yours Trevor Potts.mp3","trackType":"mp3"} Jun 28 14:43:22 spa-main volumio[1176]: verbose: CURRENT POSITION 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::syncState stateService play Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::syncState currentStatus play Jun 28 14:43:22 spa-main volumio[1176]: info: Received an update from plugin. extracting info from payload Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 28 14:43:22 spa-main volumio[1176]: info: ------------------------------ 60ms Jun 28 14:43:22 spa-main volumio[1176]: info: ------------------------------ 58ms Jun 28 14:43:22 spa-main volumio[1176]: info: ------------------------------ 58ms Jun 28 14:43:22 spa-main volumio[1176]: info: CoreStateMachine::pushState Jun 28 14:43:22 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:43:22 spa-main volumio[1176]: info: CoreCommandRouter::volumioPushState Jun 28 14:43:57 spa-main volumiologrotate[600]: ls: cannot access '/var/log/samba/log.wb-SPA': No such file or directory Jun 28 14:43:57 spa-main volumiologrotate[600]: ls: cannot access 'MAIN': No such file or directory Jun 28 14:44:22 spa-main volumio[1176]: error: Could not retrieve plugin audio_interface multiroom Jun 28 14:44:22 spa-main volumio[1176]: error: Could not retrieve plugin audio_interface multiroom Jun 28 14:44:22 spa-main volumio[1176]: error: Could not retrieve plugin audio_interface multiroom Jun 28 14:44:22 spa-main volumio[1176]: error: Could not retrieve plugin audio_interface multiroom Jun 28 14:44:22 spa-main volumio[1176]: error: Could not retrieve plugin audio_interface multiroom Jun 28 14:44:22 spa-main volumio[1176]: error: Could not retrieve plugin audio_interface multiroom Jun 28 14:44:22 spa-main volumio[1176]: error: Could not retrieve plugin audio_interface multiroom Jun 28 14:44:38 spa-main volumio[1176]: verbose: New Socket.io Connection to 10.163.8.181 from 10.163.8.253 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15 Total Clients: 9 Jun 28 14:44:38 spa-main volumio[1176]: verbose: New Socket.io Connection to 10.163.8.181 from 10.163.8.253 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15 Total Clients: 10 Jun 28 14:44:42 spa-main volumio[1176]: verbose: New Socket.io Connection to 10.163.8.181 from 10.163.8.253 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15 Total Clients: 11 Jun 28 14:44:42 spa-main volumio[1176]: info: CoreCommandRouter::volumioGetState Jun 28 14:44:42 spa-main volumio[1176]: info: CorePlayQueue::getTrack 30 Jun 28 14:44:42 spa-main volumio[1176]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 28 14:44:42 spa-main volumio[1176]: error: Could not retrieve plugin audio_interface multiroom Jun 28 14:44:42 spa-main volumio[1176]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 14:44:42 spa-main volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 14:44:42 spa-main volumio[1176]: info: Listing playlists Jun 28 14:44:45 spa-main volumio[1176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 14:44:45 spa-main volumio[1176]: Error [ERR_TLS_CERT_ALTNAME_INVALID]: Hostname/IP does not match certificate's altnames: Host: lastfm.freetls.fastly.net. is not in the cert's altnames: DNS:retail.soundyoucanfeel.me Jun 28 14:44:45 spa-main volumio[1176]: at new NodeError (internal/errors.js:322:7) Jun 28 14:44:45 spa-main volumio[1176]: at Object.checkServerIdentity (tls.js:347:12) Jun 28 14:44:45 spa-main volumio[1176]: at TLSSocket.onConnectSecure (_tls_wrap.js:1525:27) Jun 28 14:44:45 spa-main volumio[1176]: at TLSSocket.emit (events.js:400:28) Jun 28 14:44:45 spa-main volumio[1176]: at TLSSocket._finishInit (_tls_wrap.js:937:8) Jun 28 14:44:45 spa-main volumio[1176]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:709:12) { Jun 28 14:44:45 spa-main volumio[1176]: reason: "Host: lastfm.freetls.fastly.net. is not in the cert's altnames: DNS:retail.soundyoucanfeel.me", Jun 28 14:44:45 spa-main volumio[1176]: host: 'lastfm.freetls.fastly.net', Jun 28 14:44:45 spa-main volumio[1176]: cert: { Jun 28 14:44:45 spa-main volumio[1176]: subject: [Object: null prototype] { CN: 'retail.soundyoucanfeel.me' }, Jun 28 14:44:45 spa-main volumio[1176]: issuer: [Object: null prototype] { C: 'US', O: "Let's Encrypt", CN: 'E6' }, Jun 28 14:44:45 spa-main volumio[1176]: subjectaltname: 'DNS:retail.soundyoucanfeel.me', Jun 28 14:44:45 spa-main volumio[1176]: infoAccess: [Object: null prototype] { Jun 28 14:44:45 spa-main volumio[1176]: 'OCSP - URI': [Array], Jun 28 14:44:45 spa-main volumio[1176]: 'CA Issuers - URI': [Array] Jun 28 14:44:45 spa-main volumio[1176]: }, Jun 28 14:44:45 spa-main volumio[1176]: bits: 384, Jun 28 14:44:45 spa-main volumio[1176]: pubkey: , Jun 28 14:44:45 spa-main volumio[1176]: asn1Curve: 'secp384r1', Jun 28 14:44:45 spa-main volumio[1176]: nistCurve: 'P-384', Jun 28 14:44:45 spa-main volumio[1176]: valid_from: 'Apr 28 03:36:45 2025 GMT', Jun 28 14:44:45 spa-main volumio[1176]: valid_to: 'Jul 27 03:36:44 2025 GMT', Jun 28 14:44:45 spa-main volumio[1176]: fingerprint: '2C:20:BE:9A:92:8E:B4:98:EB:F5:6A:8C:E4:D8:8C:F4:2F:F5:12:2C', Jun 28 14:44:45 spa-main volumio[1176]: fingerprint256: '3B:B8:8D:75:E4:6D:1F:40:E8:92:34:C3:C6:B6:AC:BB:25:4B:C7:29:AD:F6:D0:D0:F4:F5:1E:1D:EE:11:9F:C0', Jun 28 14:44:45 spa-main volumio[1176]: ext_key_usage: [ '1.3.6.1.5.5.7.3.1', '1.3.6.1.5.5.7.3.2' ], Jun 28 14:44:45 spa-main volumio[1176]: serialNumber: '057C433BC5FCC264600F5E0C05A8F648F712', Jun 28 14:44:45 spa-main volumio[1176]: raw: , Jun 28 14:44:45 spa-main volumio[1176]: issuerCertificate: { Jun 28 14:44:45 spa-main volumio[1176]: subject: [Object: null prototype], Jun 28 14:44:45 spa-main volumio[1176]: issuer: [Object: null prototype], Jun 28 14:44:45 spa-main volumio[1176]: infoAccess: [Object: null prototype], Jun 28 14:44:45 spa-main volumio[1176]: bits: 384, Jun 28 14:44:45 spa-main volumio[1176]: pubkey: , Jun 28 14:44:45 spa-main volumio[1176]: asn1Curve: 'secp384r1', Jun 28 14:44:45 spa-main volumio[1176]: nistCurve: 'P-384', Jun 28 14:44:45 spa-main volumio[1176]: valid_from: 'Mar 13 00:00:00 2024 GMT', Jun 28 14:44:45 spa-main volumio[1176]: valid_to: 'Mar 12 23:59:59 2027 GMT', Jun 28 14:44:45 spa-main volumio[1176]: fingerprint: 'C9:4D:C4:83:1A:90:1A:9F:EC:0F:B4:9B:71:BD:49:B5:AA:D4:FA:D0', Jun 28 14:44:45 spa-main volumio[1176]: fingerprint256: '76:E9:E2:88:AA:FC:0E:37:F4:39:0C:BF:94:6A:AD:99:7D:5C:1C:90:1B:3C:E5:13:D3:D8:FA:DB:AB:E2:AB:85', Jun 28 14:44:45 spa-main volumio[1176]: ext_key_usage: [Array], Jun 28 14:44:45 spa-main volumio[1176]: serialNumber: 'B0573E9173972770DBB487CB3A452B38', Jun 28 14:44:45 spa-main volumio[1176]: raw: , Jun 28 14:44:45 spa-main volumio[1176]: issuerCertificate: [Object] Jun 28 14:44:45 spa-main volumio[1176]: } Jun 28 14:44:45 spa-main volumio[1176]: }, Jun 28 14:44:45 spa-main volumio[1176]: code: 'ERR_TLS_CERT_ALTNAME_INVALID' Jun 28 14:44:45 spa-main volumio[1176]: } Jun 28 14:44:45 spa-main volumio[1176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 14:44:46 spa-main sudo[12387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-28 14:43 Jun 28 14:44:46 spa-main sudo[12387]: 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"