-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-06-30 20:21:38 CEST. -- Jun 30 20:20:01 streamx ntpd[771]: Soliciting pool server 195.46.37.22 Jun 30 20:20:01 streamx ntpd[771]: Soliciting pool server 156.17.20.254 Jun 30 20:20:01 streamx ntpd[771]: Soliciting pool server 192.86.14.67 Jun 30 20:20:01 streamx ntpd[771]: Soliciting pool server 162.159.200.1 Jun 30 20:20:02 streamx ntpd[771]: Soliciting pool server 79.190.98.149 Jun 30 20:20:02 streamx ntpd[771]: Soliciting pool server 178.215.228.24 Jun 30 20:20:02 streamx ntpd[771]: Soliciting pool server 162.159.200.123 Jun 30 20:20:02 streamx ntpd[771]: Soliciting pool server 212.127.95.218 Jun 30 20:20:03 streamx ntpd[771]: Soliciting pool server 85.115.212.254 Jun 30 20:20:03 streamx ntpd[771]: Soliciting pool server 89.161.47.136 Jun 30 20:20:03 streamx ntpd[771]: Soliciting pool server 178.255.46.151 Jun 30 20:20:04 streamx ntpd[771]: Soliciting pool server 91.212.242.21 Jun 30 20:20:04 streamx ntpd[771]: Soliciting pool server 54.37.233.160 Jun 30 20:20:04 streamx ntpd[771]: Soliciting pool server 46.175.224.7 Jun 30 20:20:04 streamx volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 20:20:04 streamx volumio[1063]: info: Received Get System Info Jun 30 20:20:04 streamx volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 20:20:04 streamx volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 20:20:04 streamx volumio[1063]: info: Discovery: Getting this device information Jun 30 20:20:04 streamx volumio[1063]: info: CoreCommandRouter::volumioGetState Jun 30 20:20:04 streamx volumio[1063]: info: CorePlayQueue::getTrack 0 Jun 30 20:20:04 streamx volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 20:20:05 streamx ntpd[771]: Soliciting pool server 2a00:d5c0:10:10::136 Jun 30 20:20:05 streamx ntpd[771]: Soliciting pool server 193.59.134.156 Jun 30 20:20:05 streamx ntpd[771]: Soliciting pool server 194.146.251.100 Jun 30 20:20:06 streamx ntpd[771]: Soliciting pool server 46.174.178.150 Jun 30 20:20:06 streamx volumio[1063]: info: Clearing queue after UPNP request Jun 30 20:20:07 streamx volumio[1063]: info: CoreStateMachine::ClearQueue Jun 30 20:20:07 streamx volumio[1063]: info: CoreStateMachine::stop Jun 30 20:20:07 streamx volumio[1063]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 30 20:20:07 streamx volumio[1063]: info: CorePlayQueue::clearPlayQueue Jun 30 20:20:07 streamx volumio[1063]: info: CorePlayQueue::saveQueue Jun 30 20:20:07 streamx volumio[1063]: info: CoreCommandRouter::volumioPushState Jun 30 20:20:07 streamx volumio[1063]: info: MRS: Pushing multiroomSync output update for this device Jun 30 20:20:07 streamx volumio[1063]: info: MRS: Pushing multiroomSync output Jun 30 20:20:07 streamx volumio[1063]: info: CoreCommandRouter::volumioGetState Jun 30 20:20:07 streamx volumio[1063]: info: CorePlayQueue::getTrack 0 Jun 30 20:20:07 streamx volumio[1063]: info: CoreCommandRouter::volumioPushQueue Jun 30 20:20:07 streamx volumio[1063]: error: Upnp client error: Error: This socket has been ended by the other party Jun 30 20:20:08 streamx ntpd[771]: receive: Unexpected origin timestamp 0xec0d53d8.12e2c0b7 does not match aorg 0000000000.00000000 from server@192.86.14.67 xmt 0xec0d53d8.61bb3d95 Jun 30 20:20:08 streamx ntpd[771]: receive: Unexpected origin timestamp 0xec0d53d8.12de17ce does not match aorg 0000000000.00000000 from server@46.175.224.7 xmt 0xec0d53d8.624063fd Jun 30 20:20:08 streamx ntpd[771]: receive: Unexpected origin timestamp 0xec0d53d8.12dab03f does not match aorg 0000000000.00000000 from server@194.146.251.100 xmt 0xec0d53d8.61dd9311 Jun 30 20:20:08 streamx ntpd[771]: receive: Unexpected origin timestamp 0xec0d53d8.12e3a031 does not match aorg 0000000000.00000000 from server@195.46.37.22 xmt 0xec0d53d8.6406c4dd Jun 30 20:20:08 streamx ntpd[771]: receive: Unexpected origin timestamp 0xec0d53d8.12e0ea5c does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xec0d53d8.63b6cf74 Jun 30 20:20:08 streamx ntpd[771]: receive: Unexpected origin timestamp 0xec0d53d8.12df21ab does not match aorg 0000000000.00000000 from server@89.161.47.136 xmt 0xec0d53d8.6a218fc0 Jun 30 20:20:09 streamx volumio[1063]: info: Starting UPNP Playback Jun 30 20:20:09 streamx volumio[1063]: info: Preparing playback through UPNP Jun 30 20:20:09 streamx volumio[1063]: info: CoreCommandRouter::volumioGetState Jun 30 20:20:09 streamx volumio[1063]: info: CorePlayQueue::getTrack 0 Jun 30 20:20:09 streamx volumio[1063]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 30 20:20:09 streamx volumio[1063]: info: Jun 30 20:20:09 streamx volumio[1063]: ---------------------------- MPD announces system playlist update Jun 30 20:20:09 streamx volumio[1063]: info: Ignoring MPD Status Update Jun 30 20:20:09 streamx volumio[1063]: info: ------------------------------ 3ms Jun 30 20:20:09 streamx volumio[1063]: info: Jun 30 20:20:09 streamx volumio[1063]: ---------------------------- MPD announces system playlist update Jun 30 20:20:09 streamx volumio[1063]: info: Ignoring MPD Status Update Jun 30 20:20:09 streamx volumio[1063]: info: ------------------------------ 2ms Jun 30 20:20:09 streamx volumio[1063]: info: Jun 30 20:20:09 streamx volumio[1063]: ---------------------------- MPD announces system playlist update Jun 30 20:20:09 streamx volumio[1063]: info: Ignoring MPD Status Update Jun 30 20:20:09 streamx volumio[1063]: info: ------------------------------ 2ms Jun 30 20:20:09 streamx volumio[1063]: info: Jun 30 20:20:09 streamx volumio[1063]: ---------------------------- MPD announces system playlist update Jun 30 20:20:09 streamx volumio[1063]: info: Ignoring MPD Status Update Jun 30 20:20:09 streamx volumio[1063]: info: ------------------------------ 2ms Jun 30 20:20:09 streamx volumio[1063]: info: Jun 30 20:20:09 streamx volumio[1063]: ---------------------------- MPD announces system playlist update Jun 30 20:20:09 streamx volumio[1063]: info: Ignoring MPD Status Update Jun 30 20:20:09 streamx volumio[1063]: info: ------------------------------ 2ms Jun 30 20:20:09 streamx volumio[1063]: info: Jun 30 20:20:09 streamx volumio[1063]: ---------------------------- MPD announces system playlist update Jun 30 20:20:09 streamx volumio[1063]: info: Ignoring MPD Status Update Jun 30 20:20:09 streamx volumio[1063]: info: ------------------------------ 2ms Jun 30 20:20:09 streamx volumio[1063]: info: Jun 30 20:20:09 streamx volumio[1063]: ---------------------------- MPD announces state update: player Jun 30 20:20:09 streamx volumio[1063]: info: ControllerMpd::getState Jun 30 20:20:09 streamx volumio[1063]: verbose: ControllerMpd::sendMpdCommand status Jun 30 20:20:09 streamx volumio[1063]: info: sendMpdCommand status took 12 milliseconds Jun 30 20:20:09 streamx volumio[1063]: verbose: ControllerMpd::parseState Jun 30 20:20:09 streamx volumio[1063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 20:20:09 streamx volumio[1063]: info: Jun 30 20:20:09 streamx volumio[1063]: ---------------------------- MPD announces state update: player Jun 30 20:20:09 streamx volumio[1063]: info: ControllerMpd::getState Jun 30 20:20:09 streamx volumio[1063]: verbose: ControllerMpd::sendMpdCommand status Jun 30 20:20:09 streamx volumio[1063]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 30 20:20:09 streamx volumio[1063]: verbose: ControllerMpd::parseTrackInfo Jun 30 20:20:09 streamx volumio[1063]: info: ControllerMpd::pushState Jun 30 20:20:09 streamx volumio[1063]: info: CoreCommandRouter::servicePushState Jun 30 20:20:09 streamx volumio[1063]: verbose: In UPNP mode Jun 30 20:20:09 streamx volumio[1063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"Liveaudio on zaxon","album":null,"uri":"http://192.168.0.101:8080/dHlwZT0iYnJpZGdlIix1ZG49InV1aWQ6MjY2NzBmM2YtYjI4ZC1kY2MzLTAzZWYtZDgzYWRkNjcwNzcyIg%3D%3D/stream.mp3","trackType":"mp3"} Jun 30 20:20:09 streamx volumio[1063]: verbose: CURRENT POSITION 0 Jun 30 20:20:09 streamx volumio[1063]: info: CoreStateMachine::syncState stateService play Jun 30 20:20:09 streamx volumio[1063]: info: CoreStateMachine::syncState currentStatus stop Jun 30 20:20:09 streamx volumio[1063]: info: ------------------------------ 19ms Jun 30 20:20:09 streamx volumio[1063]: info: sendMpdCommand status took 4 milliseconds Jun 30 20:20:09 streamx volumio[1063]: verbose: ControllerMpd::parseState Jun 30 20:20:09 streamx volumio[1063]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 30 20:20:09 streamx volumio[1063]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 30 20:20:09 streamx volumio[1063]: verbose: ControllerMpd::parseTrackInfo Jun 30 20:20:09 streamx volumio[1063]: info: ControllerMpd::pushState Jun 30 20:20:09 streamx volumio[1063]: info: CoreCommandRouter::servicePushState Jun 30 20:20:09 streamx volumio[1063]: verbose: In UPNP mode Jun 30 20:20:09 streamx volumio[1063]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"Liveaudio on zaxon","album":null,"uri":"http://192.168.0.101:8080/dHlwZT0iYnJpZGdlIix1ZG49InV1aWQ6MjY2NzBmM2YtYjI4ZC1kY2MzLTAzZWYtZDgzYWRkNjcwNzcyIg%3D%3D/stream.mp3","trackType":"mp3"} Jun 30 20:20:09 streamx volumio[1063]: verbose: CURRENT POSITION 0 Jun 30 20:20:09 streamx volumio[1063]: info: CoreStateMachine::syncState stateService play Jun 30 20:20:09 streamx volumio[1063]: info: CoreStateMachine::syncState currentStatus play Jun 30 20:20:09 streamx volumio[1063]: info: Received an update from plugin. extracting info from payload Jun 30 20:20:09 streamx volumio[1063]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 30 20:20:09 streamx volumio[1063]: info: CoreStateMachine::pushState Jun 30 20:20:09 streamx volumio[1063]: info: CorePlayQueue::getTrack 0 Jun 30 20:20:09 streamx volumio[1063]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jun 30 20:20:09 streamx volumio[1063]: info: ------------------------------ 9ms Jun 30 20:21:37 streamx volumio[1063]: info: Tunnel connection is inactive, restarting it Jun 30 20:21:37 streamx volumio[1063]: info: Starting Tunnel 1 Jun 30 20:21:37 streamx volumio[1063]: info: Starting Tunnel Connection Checker Jun 30 20:21:37 streamx sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 30 20:21:37 streamx sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 20:21:37 streamx autossh[1463]: received signal to exit (15) Jun 30 20:21:37 streamx systemd[1]: Stopping MyVolumio SSH Tunnel... Jun 30 20:21:37 streamx systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jun 30 20:21:37 streamx systemd[1]: sshtunnel.service: Succeeded. Jun 30 20:21:37 streamx systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 30 20:21:37 streamx volumio[1063]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 30 20:21:37 streamx volumio[1063]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 30 20:21:37 streamx systemd[1]: Started MyVolumio SSH Tunnel. Jun 30 20:21:37 streamx sudo[1819]: pam_unix(sudo:session): session closed for user root Jun 30 20:21:37 streamx autossh[1822]: port set to 0, monitoring disabled Jun 30 20:21:37 streamx autossh[1822]: starting ssh (count 1) Jun 30 20:21:37 streamx autossh[1822]: ssh child pid is 1825 Jun 30 20:21:37 streamx volumio[1063]: info: Remote SSH Started Jun 30 20:21:37 streamx volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 30 20:21:37 streamx volumio[1063]: info: CoreCommandRouter::volumioGetState Jun 30 20:21:37 streamx volumio[1063]: info: CorePlayQueue::getTrack 0 Jun 30 20:21:37 streamx volumio[1063]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 20:21:37 streamx volumio[1063]: TypeError: Cannot read property 'name' of undefined Jun 30 20:21:37 streamx volumio[1063]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jun 30 20:21:37 streamx volumio[1063]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jun 30 20:21:37 streamx volumio[1063]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Jun 30 20:21:37 streamx volumio[1063]: at Socket.emit (events.js:315:20) Jun 30 20:21:37 streamx volumio[1063]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 30 20:21:37 streamx volumio[1063]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 30 20:21:37 streamx volumio[1063]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 20:21:38 streamx volumiossh-tunnel[1821]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Jun 30 20:21:38 streamx sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 20:20 Jun 30 20:21:38 streamx sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"