-- Logs begin at Sat 2026-01-10 04:47:26 MSK, end at Mon 2026-01-12 16:15:14 MSK. -- Jan 12 16:14:12 volumio volumio[833]: info: Tunnel connection is inactive, restarting it Jan 12 16:14:12 volumio volumio[833]: info: Starting Tunnel 1 Jan 12 16:14:12 volumio volumio[833]: info: Starting Tunnel Connection Checker Jan 12 16:14:12 volumio sudo[11846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 12 16:14:12 volumio sudo[11846]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 16:14:12 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 16:14:12 volumio sudo[11846]: pam_unix(sudo:session): session closed for user root Jan 12 16:14:12 volumio autossh[11854]: port set to 0, monitoring disabled Jan 12 16:14:12 volumio autossh[11854]: starting ssh (count 1) Jan 12 16:14:12 volumio autossh[11854]: ssh child pid is 11859 Jan 12 16:14:12 volumio volumio[833]: info: Remote SSH Started Jan 12 16:14:12 volumio volumio[833]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 12 16:14:12 volumio volumio[833]: info: CoreCommandRouter::volumioGetState Jan 12 16:14:12 volumio volumio[833]: info: CorePlayQueue::getTrack 6 Jan 12 16:14:12 volumio volumiossh-tunnel[11852]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Jan 12 16:14:17 volumio volumiossh-tunnel[11852]: Connection to eu7.myvolumio.org closed by remote host. Jan 12 16:14:17 volumio autossh[11854]: ssh exited prematurely with status 255; autossh exiting Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jan 12 16:14:17 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jan 12 16:14:17 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 16:14:17 volumio autossh[11875]: port set to 0, monitoring disabled Jan 12 16:14:17 volumio autossh[11875]: starting ssh (count 1) Jan 12 16:14:17 volumio autossh[11875]: ssh child pid is 11878 Jan 12 16:14:17 volumio volumiossh-tunnel[11874]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 16:14:17 volumio autossh[11875]: ssh exited prematurely with status 255; autossh exiting Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jan 12 16:14:17 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jan 12 16:14:17 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 16:14:17 volumio autossh[11882]: port set to 0, monitoring disabled Jan 12 16:14:17 volumio autossh[11882]: starting ssh (count 1) Jan 12 16:14:17 volumio autossh[11882]: ssh child pid is 11885 Jan 12 16:14:17 volumio volumiossh-tunnel[11881]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 16:14:17 volumio autossh[11882]: ssh exited prematurely with status 255; autossh exiting Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 16:14:17 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jan 12 16:14:18 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jan 12 16:14:18 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 16:14:18 volumio autossh[11887]: port set to 0, monitoring disabled Jan 12 16:14:18 volumio autossh[11887]: starting ssh (count 1) Jan 12 16:14:18 volumio autossh[11887]: ssh child pid is 11890 Jan 12 16:14:18 volumio volumiossh-tunnel[11886]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 16:14:18 volumio autossh[11887]: ssh exited prematurely with status 255; autossh exiting Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jan 12 16:14:18 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jan 12 16:14:18 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 12 16:14:18 volumio autossh[11894]: port set to 0, monitoring disabled Jan 12 16:14:18 volumio autossh[11894]: starting ssh (count 1) Jan 12 16:14:18 volumio autossh[11894]: ssh child pid is 11897 Jan 12 16:14:18 volumio volumiossh-tunnel[11893]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 16:14:18 volumio autossh[11894]: ssh exited prematurely with status 255; autossh exiting Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jan 12 16:14:18 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Jan 12 16:14:18 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 16:14:18 volumio systemd[1]: Failed to start MyVolumio SSH Tunnel. Jan 12 16:15:00 volumio volumio[833]: info: CorePlayQueue::getTrack 6 Jan 12 16:15:00 volumio volumio[833]: info: CorePlayQueue::getTrack 7 Jan 12 16:15:00 volumio volumio[833]: info: Prefetching next song Jan 12 16:15:00 volumio volumio[833]: info: DOING PREFETCH IN MPD Jan 12 16:15:00 volumio volumio[833]: verbose: ControllerMpd::sendMpdCommand add "USB/Seagate_Expansion_Drive/Music Temp 2/Al Jarreau/Al Jarreau - 1992 - Heaven and Earth (9031-77466-2)/08. Al Jarreau, – If I break.flac" Jan 12 16:15:00 volumio volumio[833]: info: Jan 12 16:15:00 volumio volumio[833]: ---------------------------- MPD announces system playlist update Jan 12 16:15:00 volumio volumio[833]: info: Ignoring MPD Status Update Jan 12 16:15:00 volumio volumio[833]: info: sendMpdCommand add "USB/Seagate_Expansion_Drive/Music Temp 2/Al Jarreau/Al Jarreau - 1992 - Heaven and Earth (9031-77466-2)/08. Al Jarreau, – If I break.flac" took 2 milliseconds Jan 12 16:15:00 volumio volumio[833]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 12 16:15:00 volumio volumio[833]: info: ------------------------------ 2ms Jan 12 16:15:00 volumio volumio[833]: info: sendMpdCommand consume 1 took 1 milliseconds Jan 12 16:15:04 volumio volumio[833]: info: CoreStateMachine::startPlaybackTimer Jan 12 16:15:04 volumio volumio[833]: info: CorePlayQueue::getTrack 7 Jan 12 16:15:05 volumio volumio[833]: info: Jan 12 16:15:05 volumio volumio[833]: ---------------------------- MPD announces system playlist update Jan 12 16:15:05 volumio volumio[833]: info: Ignoring MPD Status Update Jan 12 16:15:05 volumio volumio[833]: info: Jan 12 16:15:05 volumio volumio[833]: ---------------------------- MPD announces state update: player Jan 12 16:15:05 volumio volumio[833]: info: ControllerMpd::getState Jan 12 16:15:05 volumio volumio[833]: verbose: ControllerMpd::sendMpdCommand status Jan 12 16:15:05 volumio volumio[833]: info: ------------------------------ 2ms Jan 12 16:15:05 volumio volumio[833]: info: sendMpdCommand status took 1 milliseconds Jan 12 16:15:05 volumio volumio[833]: verbose: ControllerMpd::parseState Jan 12 16:15:05 volumio volumio[833]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 16:15:05 volumio volumio[833]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 16:15:05 volumio volumio[833]: verbose: ControllerMpd::parseTrackInfo Jan 12 16:15:05 volumio volumio[833]: info: ControllerMpd::pushState Jan 12 16:15:05 volumio volumio[833]: info: CoreCommandRouter::servicePushState Jan 12 16:15:05 volumio volumio[833]: info: CorePlayQueue::getTrack 7 Jan 12 16:15:05 volumio volumio[833]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"377 Kbps","isStreaming":false,"title":"If I break","artist":"Al Jarreau,","album":"Heaven and Earth (9031-77466-2)","uri":"USB/Seagate_Expansion_Drive/Music Temp 2/Al Jarreau/Al Jarreau - 1992 - Heaven and Earth (9031-77466-2)/08. Al Jarreau, – If I break.flac","trackType":"flac"} Jan 12 16:15:05 volumio volumio[833]: verbose: CURRENT POSITION 7 Jan 12 16:15:05 volumio volumio[833]: info: CoreStateMachine::syncState stateService play Jan 12 16:15:05 volumio volumio[833]: info: CoreStateMachine::syncState currentStatus play Jan 12 16:15:05 volumio volumio[833]: info: Received an update from plugin. extracting info from payload Jan 12 16:15:05 volumio volumio[833]: info: CoreStateMachine::pushState Jan 12 16:15:05 volumio volumio[833]: info: CorePlayQueue::getTrack 7 Jan 12 16:15:05 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 16:15:05 volumio volumio[833]: info: CoreCommandRouter::volumioPushState Jan 12 16:15:05 volumio volumio[833]: info: CoreStateMachine::pushState Jan 12 16:15:05 volumio volumio[833]: info: CorePlayQueue::getTrack 7 Jan 12 16:15:05 volumio volumio[833]: info: CoreCommandRouter::volumioPushState Jan 12 16:15:05 volumio volumio[833]: info: ------------------------------ 12ms Jan 12 16:15:05 volumio volumio[833]: info: [LastFM] Current track has sufficient metadata: title (If I break) and artist (Al Jarreau,) passed on explicitly Jan 12 16:15:05 volumio volumio[833]: info: CoreStateMachine::pushState Jan 12 16:15:05 volumio volumio[833]: info: CorePlayQueue::getTrack 7 Jan 12 16:15:05 volumio volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 16:15:05 volumio volumio[833]: info: CoreCommandRouter::volumioPushState Jan 12 16:15:14 volumio volumio[833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 16:15:14 volumio volumio[833]: TypeError: Cannot read property '@' of undefined Jan 12 16:15:14 volumio volumio[833]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Jan 12 16:15:14 volumio volumio[833]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Jan 12 16:15:14 volumio volumio[833]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:308:18) Jan 12 16:15:14 volumio volumio[833]: at Parser.emit (events.js:400:28) Jan 12 16:15:14 volumio volumio[833]: at SAXParser.onerror (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:123:26) Jan 12 16:15:14 volumio volumio[833]: at emit (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:624:35) Jan 12 16:15:14 volumio volumio[833]: at error (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:653:5) Jan 12 16:15:14 volumio volumio[833]: at strictFail (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:677:7) Jan 12 16:15:14 volumio volumio[833]: at closeTag (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:871:9) Jan 12 16:15:14 volumio volumio[833]: at SAXParser.write (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:1436:13) Jan 12 16:15:14 volumio volumio[833]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:323:31) Jan 12 16:15:14 volumio volumio[833]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Jan 12 16:15:14 volumio volumio[833]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Jan 12 16:15:14 volumio volumio[833]: at IncomingMessage.emit (events.js:412:35) Jan 12 16:15:14 volumio volumio[833]: at endReadableNT (internal/streams/readable.js:1333:12) Jan 12 16:15:14 volumio volumio[833]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 12 16:15:14 volumio volumio[833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 16:15:14 volumio sudo[12033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 16:14 Jan 12 16:15:14 volumio sudo[12033]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"